builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1476484794.07 results: success (0) buildid: 20161014153502 builduid: 69f3d7d0f78c4c8ca7bfd0f03475276d revision: f2d64ec287a1c2d55e4b03209ea5ee392395b91d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.072195) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.072806) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.073126) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.088044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.088351) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-14 15:39:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.8M=0.001s 2016-10-14 15:39:54 (19.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098189 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.200707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.201066) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013941 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.250750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.251128) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f2d64ec287a1c2d55e4b03209ea5ee392395b91d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f2d64ec287a1c2d55e4b03209ea5ee392395b91d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-14 15:39:54,447 truncating revision to first 12 chars 2016-10-14 15:39:54,447 Setting DEBUG logging. 2016-10-14 15:39:54,447 attempt 1/10 2016-10-14 15:39:54,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/f2d64ec287a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 15:39:54,796 unpacking tar archive at: mozilla-inbound-f2d64ec287a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.681531 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.950137) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.950951) ========= script_repo_revision: f2d64ec287a1c2d55e4b03209ea5ee392395b91d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.951961) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.952481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 15:39:54.963518) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-14 15:39:54.964304) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:55 INFO - MultiFileLogger online at 20161014 15:39:55 in /builds/slave/test 15:39:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:39:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:55 INFO - {'append_to_log': False, 15:39:55 INFO - 'base_work_dir': '/builds/slave/test', 15:39:55 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:39:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:55 INFO - 'branch': 'Mozilla-Inbound', 15:39:55 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:55 INFO - 'config_files': ('talos/linux_config.py',), 15:39:55 INFO - 'default_actions': ('clobber', 15:39:55 INFO - 'read-buildbot-config', 15:39:55 INFO - 'download-and-extract', 15:39:55 INFO - 'populate-webroot', 15:39:55 INFO - 'create-virtualenv', 15:39:55 INFO - 'install', 15:39:55 INFO - 'run-tests'), 15:39:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:55 INFO - 'download_minidump_stackwalk': True, 15:39:55 INFO - 'download_symbols': 'ondemand', 15:39:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:55 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:55 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:55 INFO - 'installer_path': 'installer.exe', 15:39:55 INFO - 'log_level': 'info', 15:39:55 INFO - 'log_name': 'talos', 15:39:55 INFO - 'log_to_console': True, 15:39:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:55 INFO - 'opt_config_files': (), 15:39:55 INFO - 'pip_index': False, 15:39:55 INFO - 'sps_profile': False, 15:39:55 INFO - 'sps_profile_interval': 0, 15:39:55 INFO - 'suite': 'dromaeojs', 15:39:55 INFO - 'system_bits': '32', 15:39:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:55 INFO - 'title': 'talos-linux64-ix-037', 15:39:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:55 INFO - 'use_talos_json': True, 15:39:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:55 INFO - 'work_dir': 'build'} 15:39:55 INFO - [mozharness: 2016-10-14 22:39:55.259840Z] Running clobber step. 15:39:55 INFO - Running pre-action listener: _resource_record_pre_action 15:39:55 INFO - Running main action method: clobber 15:39:55 INFO - rmtree: /builds/slave/test/build 15:39:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:55 INFO - Running post-action listener: _resource_record_post_action 15:39:55 INFO - [mozharness: 2016-10-14 22:39:55.755284Z] Finished clobber step (success) 15:39:55 INFO - [mozharness: 2016-10-14 22:39:55.755384Z] Running read-buildbot-config step. 15:39:55 INFO - Running pre-action listener: _resource_record_pre_action 15:39:55 INFO - Running main action method: read_buildbot_config 15:39:55 INFO - Using buildbot properties: 15:39:55 INFO - { 15:39:55 INFO - "project": "", 15:39:55 INFO - "product": "firefox", 15:39:55 INFO - "script_repo_revision": "production", 15:39:55 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:39:55 INFO - "repository": "", 15:39:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:39:55 INFO - "buildid": "20161014153502", 15:39:55 INFO - "stage_platform": "linux64-pgo", 15:39:55 INFO - "basedir": "/builds/slave/test", 15:39:55 INFO - "buildnumber": 1830, 15:39:55 INFO - "platform": "ubuntu64_hw", 15:39:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:39:55 INFO - "slavebuilddir": "test", 15:39:55 INFO - "branch": "mozilla-inbound", 15:39:55 INFO - "revision": "f2d64ec287a1c2d55e4b03209ea5ee392395b91d", 15:39:55 INFO - "slavename": "talos-linux64-ix-037", 15:39:55 INFO - "builduid": "69f3d7d0f78c4c8ca7bfd0f03475276d", 15:39:55 INFO - "repo_path": "integration/mozilla-inbound" 15:39:55 INFO - } 15:39:55 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 15:39:55 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 15:39:55 INFO - Running post-action listener: _resource_record_post_action 15:39:55 INFO - [mozharness: 2016-10-14 22:39:55.758079Z] Finished read-buildbot-config step (success) 15:39:55 INFO - [mozharness: 2016-10-14 22:39:55.758165Z] Running download-and-extract step. 15:39:55 INFO - Running pre-action listener: _resource_record_pre_action 15:39:55 INFO - Running main action method: download_and_extract 15:39:55 INFO - mkdir: /builds/slave/test/build/tests 15:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:55 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:39:55 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:39:55 INFO - Downloaded 1361 bytes. 15:39:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 15:39:55 INFO - Using the following test package requirements: 15:39:55 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:39:55 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:39:55 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:39:55 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'jsshell-linux-x86_64.zip'], 15:39:55 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:39:55 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 15:39:55 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:39:55 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:39:55 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:39:55 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:55 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:39:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:39:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:39:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:39:55 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:39:55 INFO - Http code: 200 15:39:55 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 15:39:55 INFO - x-amz-cf-id: x09jKER7kZh9bJNrMWDBVue2QHQ9WQG2SyCeG4lX9y0P4Fq3soe9tQ== 15:39:55 INFO - x-amz-replication-status: COMPLETED 15:39:55 INFO - x-amz-version-id: gPRH0JaMS9ltywFmQ7ZXs8sQNfGz9hmq 15:39:56 INFO - Expected file size: 22117179 15:39:56 INFO - Obtained file size: 22117179 15:40:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:40:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 15:40:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:40:00 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:40:00 INFO - Http code: 200 15:40:00 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 15:40:00 INFO - x-amz-cf-id: jZXRkyjzzI7kRh4dMdLsHn5Jj4o5U_NRAeybPqlViUOrfxseFUMeUw== 15:40:00 INFO - x-amz-replication-status: COMPLETED 15:40:00 INFO - x-amz-version-id: gPRH0JaMS9ltywFmQ7ZXs8sQNfGz9hmq 15:40:00 INFO - Expected file size: 22117179 15:40:00 INFO - Obtained file size: 22117179 15:40:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 15:40:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:40:04 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:40:04 INFO - Http code: 200 15:40:04 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 15:40:04 INFO - x-amz-cf-id: XBBaAJgZrf0OL1xbV0QyZ0B0OT3j4nxya_6H9tpZvK71CdtAqdhaOQ== 15:40:04 INFO - x-amz-replication-status: COMPLETED 15:40:04 INFO - x-amz-version-id: 2l8OgScnSPCBIgmKMhx2GbxKN4MBvMwC 15:40:05 INFO - Expected file size: 14409028 15:40:05 INFO - Obtained file size: 14409028 15:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:05 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:40:05 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:40:06 INFO - Downloaded 59632446 bytes. 15:40:06 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:40:06 INFO - mkdir: /builds/slave/test/properties 15:40:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:06 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:06 INFO - Contents: 15:40:06 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 15:40:06 INFO - Running post-action listener: _resource_record_post_action 15:40:06 INFO - Running post-action listener: set_extra_try_arguments 15:40:06 INFO - [mozharness: 2016-10-14 22:40:06.803400Z] Finished download-and-extract step (success) 15:40:06 INFO - [mozharness: 2016-10-14 22:40:06.803627Z] Running populate-webroot step. 15:40:06 INFO - Running pre-action listener: _resource_record_pre_action 15:40:06 INFO - Running main action method: populate_webroot 15:40:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:06 INFO - '--tppagecycles', 15:40:06 INFO - '7'], 15:40:06 INFO - 'tests': ['tsvgm']}}, 15:40:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['damp', 'tps']}, 15:40:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'tests': ['damp', 'tps']}, 15:40:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['dromaeo_dom']}, 15:40:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['basic_compositor_video']}, 15:40:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:40:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['a11yr', 15:40:06 INFO - 'ts_paint', 15:40:06 INFO - 'tpaint', 15:40:06 INFO - 'sessionrestore', 15:40:06 INFO - 'sessionrestore_no_auto_restore', 15:40:06 INFO - 'tabpaint']}, 15:40:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['tsvgx', 15:40:06 INFO - 'tsvgr_opacity', 15:40:06 INFO - 'tart', 15:40:06 INFO - 'tscrollx', 15:40:06 INFO - 'cart']}, 15:40:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:06 INFO - 'tsvgr_opacity', 15:40:06 INFO - 'tart', 15:40:06 INFO - 'tscrollx', 15:40:06 INFO - 'cart']}, 15:40:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'talos_options': ['--disable-e10s'], 15:40:06 INFO - 'tests': ['tp5o']}, 15:40:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'tests': ['tp5o']}, 15:40:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'talos_options': ['--disable-e10s', 15:40:06 INFO - '--xperf_path', 15:40:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:06 INFO - 'tests': ['tp5n']}, 15:40:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:06 INFO - 'talos_options': ['--xperf_path', 15:40:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:06 INFO - 'tests': ['tp5n']}}, 15:40:06 INFO - 'talos.zip': {'path': '', 15:40:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:06 INFO - Running post-action listener: _resource_record_post_action 15:40:06 INFO - [mozharness: 2016-10-14 22:40:06.820674Z] Finished populate-webroot step (success) 15:40:06 INFO - [mozharness: 2016-10-14 22:40:06.820762Z] Running create-virtualenv step. 15:40:06 INFO - Running pre-action listener: _resource_record_pre_action 15:40:06 INFO - Running main action method: create_virtualenv 15:40:06 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:40:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:06 INFO - Using real prefix '/usr' 15:40:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:07 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:09 INFO - Installing pip.................done. 15:40:09 INFO - Return code: 0 15:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:40:09 INFO - Reading from file tmpfile_stdout 15:40:09 INFO - Output received: 15:40:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:40:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:40:09 INFO - Using env: {'DISPLAY': ':0', 15:40:09 INFO - 'HOME': '/home/cltbld', 15:40:09 INFO - 'LANG': 'en_US.UTF-8', 15:40:09 INFO - 'LANGUAGE': 'en_US:en', 15:40:09 INFO - 'LOGNAME': 'cltbld', 15:40:09 INFO - 'MAIL': '/var/mail/cltbld', 15:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:09 INFO - 'MOZ_NO_REMOTE': '1', 15:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:09 INFO - 'NO_EM_RESTART': '1', 15:40:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:09 INFO - 'PWD': '/builds/slave/test', 15:40:09 INFO - 'SHELL': '/bin/bash', 15:40:09 INFO - 'SHLVL': '1', 15:40:09 INFO - 'TERM': 'linux', 15:40:09 INFO - 'TMOUT': '86400', 15:40:09 INFO - 'USER': 'cltbld', 15:40:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:09 INFO - '_': '/tools/buildbot/bin/python'} 15:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:40:09 INFO - Cleaning up... 15:40:09 INFO - Return code: 0 15:40:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:40:09 INFO - Using env: {'DISPLAY': ':0', 15:40:09 INFO - 'HOME': '/home/cltbld', 15:40:09 INFO - 'LANG': 'en_US.UTF-8', 15:40:09 INFO - 'LANGUAGE': 'en_US:en', 15:40:09 INFO - 'LOGNAME': 'cltbld', 15:40:09 INFO - 'MAIL': '/var/mail/cltbld', 15:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:09 INFO - 'MOZ_NO_REMOTE': '1', 15:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:09 INFO - 'NO_EM_RESTART': '1', 15:40:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:09 INFO - 'PWD': '/builds/slave/test', 15:40:09 INFO - 'SHELL': '/bin/bash', 15:40:09 INFO - 'SHLVL': '1', 15:40:09 INFO - 'TERM': 'linux', 15:40:09 INFO - 'TMOUT': '86400', 15:40:09 INFO - 'USER': 'cltbld', 15:40:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:09 INFO - '_': '/tools/buildbot/bin/python'} 15:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:10 INFO - Downloading/unpacking psutil>=3.1.1 15:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:12 INFO - Installing collected packages: psutil 15:40:12 INFO - Running setup.py install for psutil 15:40:12 INFO - building 'psutil._psutil_linux' extension 15:40:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:40:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:40:12 INFO - building 'psutil._psutil_posix' extension 15:40:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:40:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:40:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:12 INFO - Successfully installed psutil 15:40:12 INFO - Cleaning up... 15:40:12 INFO - Return code: 0 15:40:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:40:12 INFO - Using env: {'DISPLAY': ':0', 15:40:12 INFO - 'HOME': '/home/cltbld', 15:40:12 INFO - 'LANG': 'en_US.UTF-8', 15:40:12 INFO - 'LANGUAGE': 'en_US:en', 15:40:12 INFO - 'LOGNAME': 'cltbld', 15:40:12 INFO - 'MAIL': '/var/mail/cltbld', 15:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:12 INFO - 'MOZ_NO_REMOTE': '1', 15:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:12 INFO - 'NO_EM_RESTART': '1', 15:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:12 INFO - 'PWD': '/builds/slave/test', 15:40:12 INFO - 'SHELL': '/bin/bash', 15:40:12 INFO - 'SHLVL': '1', 15:40:12 INFO - 'TERM': 'linux', 15:40:12 INFO - 'TMOUT': '86400', 15:40:12 INFO - 'USER': 'cltbld', 15:40:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:12 INFO - '_': '/tools/buildbot/bin/python'} 15:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:14 INFO - Installing collected packages: mozsystemmonitor 15:40:14 INFO - Running setup.py install for mozsystemmonitor 15:40:14 INFO - Successfully installed mozsystemmonitor 15:40:14 INFO - Cleaning up... 15:40:14 INFO - Return code: 0 15:40:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:40:14 INFO - Using env: {'DISPLAY': ':0', 15:40:14 INFO - 'HOME': '/home/cltbld', 15:40:14 INFO - 'LANG': 'en_US.UTF-8', 15:40:14 INFO - 'LANGUAGE': 'en_US:en', 15:40:14 INFO - 'LOGNAME': 'cltbld', 15:40:14 INFO - 'MAIL': '/var/mail/cltbld', 15:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:14 INFO - 'MOZ_NO_REMOTE': '1', 15:40:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:14 INFO - 'NO_EM_RESTART': '1', 15:40:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:14 INFO - 'PWD': '/builds/slave/test', 15:40:14 INFO - 'SHELL': '/bin/bash', 15:40:14 INFO - 'SHLVL': '1', 15:40:14 INFO - 'TERM': 'linux', 15:40:14 INFO - 'TMOUT': '86400', 15:40:14 INFO - 'USER': 'cltbld', 15:40:14 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:14 INFO - '_': '/tools/buildbot/bin/python'} 15:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:15 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:17 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:17 INFO - warning: no files found matching '*.txt' 15:40:17 INFO - no previously-included directories found matching 'build' 15:40:17 INFO - no previously-included directories found matching 'dist' 15:40:17 INFO - no previously-included directories found matching '.git*' 15:40:17 INFO - Installing collected packages: jsonschema, functools32 15:40:17 INFO - Running setup.py install for functools32 15:40:17 INFO - warning: no files found matching '*.txt' 15:40:17 INFO - no previously-included directories found matching 'build' 15:40:17 INFO - no previously-included directories found matching 'dist' 15:40:17 INFO - no previously-included directories found matching '.git*' 15:40:17 INFO - Successfully installed jsonschema functools32 15:40:17 INFO - Cleaning up... 15:40:17 INFO - Return code: 0 15:40:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:40:17 INFO - Using env: {'DISPLAY': ':0', 15:40:17 INFO - 'HOME': '/home/cltbld', 15:40:17 INFO - 'LANG': 'en_US.UTF-8', 15:40:17 INFO - 'LANGUAGE': 'en_US:en', 15:40:17 INFO - 'LOGNAME': 'cltbld', 15:40:17 INFO - 'MAIL': '/var/mail/cltbld', 15:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:17 INFO - 'MOZ_NO_REMOTE': '1', 15:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:17 INFO - 'NO_EM_RESTART': '1', 15:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:17 INFO - 'PWD': '/builds/slave/test', 15:40:17 INFO - 'SHELL': '/bin/bash', 15:40:17 INFO - 'SHLVL': '1', 15:40:17 INFO - 'TERM': 'linux', 15:40:17 INFO - 'TMOUT': '86400', 15:40:17 INFO - 'USER': 'cltbld', 15:40:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:17 INFO - '_': '/tools/buildbot/bin/python'} 15:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:17 INFO - Cleaning up... 15:40:17 INFO - Return code: 0 15:40:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:40:17 INFO - Using env: {'DISPLAY': ':0', 15:40:17 INFO - 'HOME': '/home/cltbld', 15:40:17 INFO - 'LANG': 'en_US.UTF-8', 15:40:17 INFO - 'LANGUAGE': 'en_US:en', 15:40:17 INFO - 'LOGNAME': 'cltbld', 15:40:17 INFO - 'MAIL': '/var/mail/cltbld', 15:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:17 INFO - 'MOZ_NO_REMOTE': '1', 15:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:17 INFO - 'NO_EM_RESTART': '1', 15:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:17 INFO - 'PWD': '/builds/slave/test', 15:40:17 INFO - 'SHELL': '/bin/bash', 15:40:17 INFO - 'SHLVL': '1', 15:40:17 INFO - 'TERM': 'linux', 15:40:17 INFO - 'TMOUT': '86400', 15:40:17 INFO - 'USER': 'cltbld', 15:40:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:17 INFO - '_': '/tools/buildbot/bin/python'} 15:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:18 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:20 INFO - Downloading docopt-0.6.1.tar.gz 15:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:20 INFO - Running setup.py install for blobuploader 15:40:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:20 INFO - Running setup.py install for requests 15:40:21 INFO - Running setup.py install for docopt 15:40:21 INFO - Successfully installed blobuploader requests docopt 15:40:21 INFO - Cleaning up... 15:40:21 INFO - Return code: 0 15:40:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:21 INFO - Using env: {'DISPLAY': ':0', 15:40:21 INFO - 'HOME': '/home/cltbld', 15:40:21 INFO - 'LANG': 'en_US.UTF-8', 15:40:21 INFO - 'LANGUAGE': 'en_US:en', 15:40:21 INFO - 'LOGNAME': 'cltbld', 15:40:21 INFO - 'MAIL': '/var/mail/cltbld', 15:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:21 INFO - 'MOZ_NO_REMOTE': '1', 15:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:21 INFO - 'NO_EM_RESTART': '1', 15:40:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:21 INFO - 'PWD': '/builds/slave/test', 15:40:21 INFO - 'SHELL': '/bin/bash', 15:40:21 INFO - 'SHLVL': '1', 15:40:21 INFO - 'TERM': 'linux', 15:40:21 INFO - 'TMOUT': '86400', 15:40:21 INFO - 'USER': 'cltbld', 15:40:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:21 INFO - '_': '/tools/buildbot/bin/python'} 15:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:21 INFO - Running setup.py (path:/tmp/pip-Pd5EQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:21 INFO - Running setup.py (path:/tmp/pip-xQjQmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:21 INFO - Running setup.py (path:/tmp/pip-qcy4IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:21 INFO - Running setup.py (path:/tmp/pip-FGfVYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:21 INFO - Running setup.py (path:/tmp/pip-x2A2pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:21 INFO - Running setup.py (path:/tmp/pip-a8VF5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:21 INFO - Running setup.py (path:/tmp/pip-CwCFhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:22 INFO - Running setup.py (path:/tmp/pip-oSCYWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:22 INFO - Running setup.py (path:/tmp/pip-TDahUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:22 INFO - Running setup.py (path:/tmp/pip-5WG9G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:22 INFO - Running setup.py (path:/tmp/pip-PFVUU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:22 INFO - Running setup.py (path:/tmp/pip-B4tOV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:22 INFO - Running setup.py (path:/tmp/pip-0Zu9yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:22 INFO - Running setup.py (path:/tmp/pip-GFPPo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:22 INFO - Running setup.py (path:/tmp/pip-rCkzv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:22 INFO - Running setup.py (path:/tmp/pip-ArIItJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:22 INFO - Running setup.py (path:/tmp/pip-HqqsAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:22 INFO - Running setup.py install for manifestparser 15:40:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:22 INFO - Running setup.py install for mozcrash 15:40:22 INFO - Running setup.py install for mozdebug 15:40:23 INFO - Running setup.py install for mozdevice 15:40:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for mozfile 15:40:23 INFO - Running setup.py install for mozhttpd 15:40:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for mozinfo 15:40:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for mozInstall 15:40:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for mozleak 15:40:23 INFO - Running setup.py install for mozlog 15:40:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for moznetwork 15:40:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:23 INFO - Running setup.py install for mozprocess 15:40:24 INFO - Running setup.py install for mozprofile 15:40:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:24 INFO - Running setup.py install for mozrunner 15:40:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:24 INFO - Running setup.py install for mozscreenshot 15:40:24 INFO - Running setup.py install for moztest 15:40:24 INFO - Running setup.py install for mozversion 15:40:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:24 INFO - Cleaning up... 15:40:24 INFO - Return code: 0 15:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:24 INFO - Using env: {'DISPLAY': ':0', 15:40:24 INFO - 'HOME': '/home/cltbld', 15:40:24 INFO - 'LANG': 'en_US.UTF-8', 15:40:24 INFO - 'LANGUAGE': 'en_US:en', 15:40:24 INFO - 'LOGNAME': 'cltbld', 15:40:24 INFO - 'MAIL': '/var/mail/cltbld', 15:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:24 INFO - 'MOZ_NO_REMOTE': '1', 15:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:24 INFO - 'NO_EM_RESTART': '1', 15:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:24 INFO - 'PWD': '/builds/slave/test', 15:40:24 INFO - 'SHELL': '/bin/bash', 15:40:24 INFO - 'SHLVL': '1', 15:40:24 INFO - 'TERM': 'linux', 15:40:24 INFO - 'TMOUT': '86400', 15:40:24 INFO - 'USER': 'cltbld', 15:40:24 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:24 INFO - '_': '/tools/buildbot/bin/python'} 15:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:24 INFO - Running setup.py (path:/tmp/pip-C42teg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:24 INFO - Running setup.py (path:/tmp/pip-NhSz7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:24 INFO - Running setup.py (path:/tmp/pip-MokEvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:25 INFO - Running setup.py (path:/tmp/pip-KNP_Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:25 INFO - Running setup.py (path:/tmp/pip-GHWBO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:25 INFO - Running setup.py (path:/tmp/pip-M1fxcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:25 INFO - Running setup.py (path:/tmp/pip-ubi4S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:25 INFO - Running setup.py (path:/tmp/pip-G4MoOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:25 INFO - Running setup.py (path:/tmp/pip-URkOjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:25 INFO - Running setup.py (path:/tmp/pip-CRNQzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:25 INFO - Running setup.py (path:/tmp/pip-GkHI_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:25 INFO - Running setup.py (path:/tmp/pip-oNrPCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:25 INFO - Running setup.py (path:/tmp/pip-TcegC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:25 INFO - Running setup.py (path:/tmp/pip-STZT_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:25 INFO - Running setup.py (path:/tmp/pip-EWVox8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:25 INFO - Running setup.py (path:/tmp/pip-_tHzre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:25 INFO - Running setup.py (path:/tmp/pip-XtFmqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:27 INFO - Downloading blessings-1.6.tar.gz 15:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:28 INFO - Installing collected packages: blessings 15:40:28 INFO - Running setup.py install for blessings 15:40:28 INFO - Successfully installed blessings 15:40:28 INFO - Cleaning up... 15:40:28 INFO - Return code: 0 15:40:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:28 INFO - Reading from file tmpfile_stdout 15:40:28 INFO - Current package versions: 15:40:28 INFO - argparse == 1.2.1 15:40:28 INFO - blessings == 1.6 15:40:28 INFO - blobuploader == 1.2.4 15:40:28 INFO - docopt == 0.6.1 15:40:28 INFO - functools32 == 3.2.3-2 15:40:28 INFO - jsonschema == 2.5.1 15:40:28 INFO - manifestparser == 1.1 15:40:28 INFO - mozInstall == 1.12 15:40:28 INFO - mozcrash == 0.17 15:40:28 INFO - mozdebug == 0.1 15:40:28 INFO - mozdevice == 0.48 15:40:28 INFO - mozfile == 1.2 15:40:28 INFO - mozhttpd == 0.7 15:40:28 INFO - mozinfo == 0.9 15:40:28 INFO - mozleak == 0.1 15:40:28 INFO - mozlog == 3.4 15:40:28 INFO - moznetwork == 0.27 15:40:28 INFO - mozprocess == 0.23 15:40:28 INFO - mozprofile == 0.28 15:40:28 INFO - mozrunner == 6.12 15:40:28 INFO - mozscreenshot == 0.1 15:40:28 INFO - mozsystemmonitor == 0.3 15:40:28 INFO - moztest == 0.7 15:40:28 INFO - mozversion == 1.4 15:40:28 INFO - psutil == 3.1.1 15:40:28 INFO - requests == 1.2.3 15:40:28 INFO - wsgiref == 0.1.2 15:40:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:28 INFO - Using env: {'DISPLAY': ':0', 15:40:28 INFO - 'HOME': '/home/cltbld', 15:40:28 INFO - 'LANG': 'en_US.UTF-8', 15:40:28 INFO - 'LANGUAGE': 'en_US:en', 15:40:28 INFO - 'LOGNAME': 'cltbld', 15:40:28 INFO - 'MAIL': '/var/mail/cltbld', 15:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:28 INFO - 'MOZ_NO_REMOTE': '1', 15:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:28 INFO - 'NO_EM_RESTART': '1', 15:40:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:28 INFO - 'PWD': '/builds/slave/test', 15:40:28 INFO - 'SHELL': '/bin/bash', 15:40:28 INFO - 'SHLVL': '1', 15:40:28 INFO - 'TERM': 'linux', 15:40:28 INFO - 'TMOUT': '86400', 15:40:28 INFO - 'USER': 'cltbld', 15:40:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:28 INFO - '_': '/tools/buildbot/bin/python'} 15:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:28 INFO - Cleaning up... 15:40:28 INFO - Return code: 0 15:40:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85afdc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bbca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:40:28 INFO - Using env: {'DISPLAY': ':0', 15:40:28 INFO - 'HOME': '/home/cltbld', 15:40:28 INFO - 'LANG': 'en_US.UTF-8', 15:40:28 INFO - 'LANGUAGE': 'en_US:en', 15:40:28 INFO - 'LOGNAME': 'cltbld', 15:40:28 INFO - 'MAIL': '/var/mail/cltbld', 15:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:28 INFO - 'MOZ_NO_REMOTE': '1', 15:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:28 INFO - 'NO_EM_RESTART': '1', 15:40:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:28 INFO - 'PWD': '/builds/slave/test', 15:40:28 INFO - 'SHELL': '/bin/bash', 15:40:28 INFO - 'SHLVL': '1', 15:40:28 INFO - 'TERM': 'linux', 15:40:28 INFO - 'TMOUT': '86400', 15:40:28 INFO - 'USER': 'cltbld', 15:40:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:28 INFO - '_': '/tools/buildbot/bin/python'} 15:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:28 INFO - Cleaning up... 15:40:28 INFO - Return code: 0 15:40:28 INFO - Running post-action listener: _resource_record_post_action 15:40:28 INFO - Running post-action listener: _start_resource_monitoring 15:40:28 INFO - Starting resource monitoring. 15:40:28 INFO - [mozharness: 2016-10-14 22:40:28.976433Z] Finished create-virtualenv step (success) 15:40:28 INFO - [mozharness: 2016-10-14 22:40:28.976895Z] Running install step. 15:40:28 INFO - Running pre-action listener: _resource_record_pre_action 15:40:28 INFO - Running main action method: install 15:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:29 INFO - Reading from file tmpfile_stdout 15:40:29 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:29 INFO - Reading from file tmpfile_stdout 15:40:29 INFO - Output received: 15:40:29 INFO - Usage: mozinstall [options] installer 15:40:29 INFO - Options: 15:40:29 INFO - -h, --help show this help message and exit 15:40:29 INFO - -d DEST, --destination=DEST 15:40:29 INFO - Directory to install application into. [default: 15:40:29 INFO - "/builds/slave/test"] 15:40:29 INFO - --app=APP Application being installed. [default: firefox] 15:40:29 INFO - mkdir: /builds/slave/test/build/application 15:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:40:46 INFO - Reading from file tmpfile_stdout 15:40:46 INFO - Output received: 15:40:46 INFO - /builds/slave/test/build/application/firefox/firefox 15:40:46 INFO - Running post-action listener: _resource_record_post_action 15:40:46 INFO - [mozharness: 2016-10-14 22:40:46.263074Z] Finished install step (success) 15:40:46 INFO - [mozharness: 2016-10-14 22:40:46.263350Z] Running run-tests step. 15:40:46 INFO - Running pre-action listener: _resource_record_pre_action 15:40:46 INFO - Running main action method: run_tests 15:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:46 INFO - Python 2.7.3 15:40:46 INFO - Return code: 0 15:40:46 INFO - grabbing minidump binary from tooltool 15:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ba1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:40:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:40:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:40:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:46 INFO - Return code: 0 15:40:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:40:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:40:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:40:46 INFO - Using env: {'DISPLAY': ':0', 15:40:46 INFO - 'HOME': '/home/cltbld', 15:40:46 INFO - 'LANG': 'en_US.UTF-8', 15:40:46 INFO - 'LANGUAGE': 'en_US:en', 15:40:46 INFO - 'LOGNAME': 'cltbld', 15:40:46 INFO - 'MAIL': '/var/mail/cltbld', 15:40:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:46 INFO - 'MOZ_NO_REMOTE': '1', 15:40:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:46 INFO - 'NO_EM_RESTART': '1', 15:40:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:46 INFO - 'PWD': '/builds/slave/test', 15:40:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:46 INFO - 'SHELL': '/bin/bash', 15:40:46 INFO - 'SHLVL': '1', 15:40:46 INFO - 'TERM': 'linux', 15:40:46 INFO - 'TMOUT': '86400', 15:40:46 INFO - 'USER': 'cltbld', 15:40:46 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129', 15:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:46 INFO - '_': '/tools/buildbot/bin/python'} 15:40:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:40:46 INFO - mozversion application_buildid: 20161014123003 15:40:46 INFO - mozversion application_changeset: f2d64ec287a1c2d55e4b03209ea5ee392395b91d 15:40:46 INFO - mozversion application_display_name: Nightly 15:40:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:46 INFO - mozversion application_name: Firefox 15:40:46 INFO - mozversion application_remotingname: firefox 15:40:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:40:46 INFO - mozversion application_vendor: Mozilla 15:40:46 INFO - mozversion application_version: 52.0a1 15:40:46 INFO - mozversion platform_buildid: 20161014123003 15:40:46 INFO - mozversion platform_changeset: f2d64ec287a1c2d55e4b03209ea5ee392395b91d 15:40:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:40:46 INFO - mozversion platform_version: 52.0a1 15:40:46 INFO - using testdate: 1476484846 15:40:46 INFO - actual date: 1476484846 15:40:46 INFO - starting webserver on 'localhost:48641' 15:40:46 INFO - SUITE-START | Running 2 tests 15:40:46 INFO - TEST-START | dromaeo_css 15:40:46 INFO - operating with platform_type : linux_ 15:40:46 INFO - Initialising browser for dromaeo_css test... 15:40:46 INFO - TEST-INFO | started process 31794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1jX0pl/profile http://localhost:48641/getInfo.html) 15:40:50 INFO - PROCESS | 31794 | __metrics Screen width/height:1600/1200 15:40:50 INFO - PROCESS | 31794 | colorDepth:24 15:40:50 INFO - PROCESS | 31794 | Browser inner width/height: 1024/697 15:40:50 INFO - PROCESS | 31794 | __metrics 15:40:51 INFO - PROCESS | 31794 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:40:51 INFO - PROCESS | 31794 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:40:52 INFO - PROCESS | 31794 | console.error: PushService: 15:40:52 INFO - PROCESS | 31794 | stateChangeProcessEnqueue: Error transitioning state 15:40:52 INFO - PROCESS | 31794 | UnknownError 15:40:52 INFO - TEST-INFO | 31794: exit 0 15:40:52 INFO - Browser initialized. 15:40:52 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:52 INFO - TEST-INFO | started process 31876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1jX0pl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:04 INFO - PROCESS | 31876 | RSS: Main: 172204032 15:41:04 INFO - PROCESS | 31876 | 15:41:55 INFO - PROCESS | 31876 | 1476484915803 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:44:07 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48641/tests/dromaeo/cssquery-ext.html) 15:44:08 INFO - PROCESS | 31876 | RSS: Main: 314249216 15:44:08 INFO - PROCESS | 31876 | 15:47:10 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-ext.html (next: http://localhost:48641/tests/dromaeo/cssquery-jquery.html) 15:47:11 INFO - PROCESS | 31876 | RSS: Main: 283860992 15:47:11 INFO - PROCESS | 31876 | 15:50:13 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48641/tests/dromaeo/cssquery-mootools.html) 15:50:14 INFO - PROCESS | 31876 | RSS: Main: 260947968 15:50:14 INFO - PROCESS | 31876 | 15:53:16 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48641/tests/dromaeo/cssquery-prototype.html) 15:53:16 INFO - PROCESS | 31876 | RSS: Main: 259100672 15:53:16 INFO - PROCESS | 31876 | 15:56:19 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48641/tests/dromaeo/cssquery-yui.html) 15:56:20 INFO - PROCESS | 31876 | RSS: Main: 215191552 15:56:20 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | Cycle 1(1): loaded http://localhost:48641/tests/dromaeo/cssquery-yui.html (next: http://localhost:48641/tests/dromaeo/cssquery-dojo.html) 15:59:23 INFO - PROCESS | 31876 | RSS: Main: 308318208 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | __start_tp_report 15:59:23 INFO - PROCESS | 31876 | _x_x_mozilla_page_load 15:59:23 INFO - PROCESS | 31876 | _x_x_mozilla_page_load_details 15:59:23 INFO - PROCESS | 31876 | |i|pagename|runs| 15:59:23 INFO - PROCESS | 31876 | |0;dojo.html;2996.5055765798643;3050.5271682889133;3079.184016235697;3097.690230976901;3100.7054329838656;5096.643234973553;5210.9739451302785;5257.263983042377;5330.973345133274;5404.108322126849;5224.29472021277;5236.581073514119;5274.103402421588;5277.182036784295;5285.841424757254;4738.28925661151;4779.330893674888;4792.568668819806;4821.108095002436;4841.540053694911;4814.63890208234;4827.131116399047;4849.490803465638;4850.369451971239;4856.7571621418965;4887.218045112782;4915.434725006634;4923.82766603169;4932.6793758405765;4951.55436010759;4991.051700176955;5008.273800298973;5009.2486127080865;5011.32347133136;5070.340855688773;4649.628029757611;4678.69588476748;4811.855644330641;4914.803407863681;4973.079980203674;5300;5304.071787437183;5314;5319.122344813101;5416.0792665247;5739.540836733051;5743.770249190027;5744.913826292609;5745.942540574582;5746.971265143689;5494.780208791643;5540.556755459554;5729.742161602738;5785.421457854223;5792.4497172768515;5362.195670649395;5406.135018397116;5407.83776486706;5465.098258787295;5477.452254774491;5906.49794767441;5909.231799866076;5909.970450147722;5910.054391297372;5920.141579470953;5346.759395827197;5388.0570900092325;5629.521490673339;5675.2054712340305;5680.4319568042865;5687.061634830289;5719.48524632785;5735.655860648374;5747.252857128547;5826.417358264224;5690.345610254791;5722.084466485426;5734.856628584235;5739.282589676291;5809.157672137601;5489.286392768994;5731.340895797037;5747.39652336507;5751.396103409165;5752.28096487939;4265.274903266452;4279.058607106432;4309.245881970705;4317.071829556598;4344.391785150082;3886.941695874564;3911.706622003361;3952.505936757905;3957.6438120569287;3957.980210098931;5469.398366179717;5479.972600136974;5486.835394938158;5493.038718224295;5560.277163968658;5763.88472230553;5771.682557459296;5771.797987070432;5772;5775.797847075332;5120.078385890575;5228.320318358589;5400.243965844785;5432.809851655173;5457.536109430743;4972.651914366032;4981.103401387712;4981.551660350513;4985;4990.775415106329;5958.344582095966;5969.611975221596;5971.485262744315;5978.222831032112;6037.154798328237;5478.753456094406;5499.7250137492165;5531.640443371168;5619.634723742944;5743.62666426681;5790.681512516683;5877.089051197071;5892.086726557391;5952.613080149777;6059.969700151471;5604.159376093618;5653.575981801464;5661.886762264814;5668.461496157858;5724.57065720081;5684.77260909559;5706.457886500776;5727.8854422912145;5729.369769325454;5741.1388291756575;5280.419153892992;5303.893922121459;5533.059379905345;5593.692346920959;5598.384177740364;5560.97219513916;5582.4138465462165;5587.776488940397;5642;5688.77244910199;5601.971990140023;5603.2155498231095;5606.579506536945;5618.101103823368;5627.690477023803;5357.303550538561;5627.465390787868;5674.432556744293;5675.091985282335;5681.460261275172;5325.680459172462;5481.150421684645;5495.038368285614;5522.6134170607675;5591.329040515164 15:59:23 INFO - PROCESS | 31876 | |1;ext.html;570.6775671745463;608.0544752909227;628.0986783965011;630.9148264984227;642.3833120204604;31428.685713142862;42209.57790422099;42490.78754606222;42743;42804.57195428041;44044.55955440445;44428;44428.777856110755;44503.77748111255;44508.77745611275;7968.721094761684;8170.264676179143;8760.605772740226;8915.509646969414;8919.152680495376;10379.688609341732;10720.892791072107;10728;11001.944990275077;11019.724506887353;10809.67570972868;10918.50866710996;10921.180911431635;10958.671239862817;10964.56141754329;10713.08938740208;10772.245942784008;10784.622538211204;10790.51442685081;10802.51388687511;10589.682309530726;10638.893611063868;10653.25427220095;10657.147428205724;10658.094062004739;40816.387754183714;41122.38316425256;41134.38298425526;41137.588624113974;41153.382699259535;3522.682958533744;3544.220271540257;3561.8041007744564;3685.207680348711;3718.9070273243387;30936.226594335323;31046.84476577625;31154.532682009787;31811.204719881956;31953.680463195302;4211.263028969918;4236.237477254093;4303.203907277164;4303.634191093761;4345.587169218919;322888.38555807306;323333;323690;324252;324667;54787;55400.16899746507;55547.44452555423;55624;55698.16452753212;58737.41262587319;58866.17007448917;58978;59023.62204724409;59088;15997.440089597041;16250.837491625169;16423.343066277215;16671.91664041672;16690.582735431468;10610.628627997983;10697.572097116185;12255;12531.93734031324;12543.811842822364;18377.26490940374;18401.63196736058;18431.354902578347;18721.719174212398;18822.71765923512;7044.15470143586;7046.401055910203;7085.185203701641;7190.209077001526;7334.156571994289;918.0727465260002;969.3892847506168;996.7956568903357;1012.7063151686076;1059.3273271472517;31170.376592468023;31422.37155256882;31422.84288578588;31908;31958.532108266052;1079.2930630437077;1087.0922779479065;1130.1580322659672;1130.1862658885589;1146.9541218351173;16150.434734784461;16406.753898691528;16570.668586628442;16674.333026678796;16699;531115;532704;537580;538332;539395;37571.4364284536;38003.619963800564;38009.80995095007;38056.238875222894;38061.61938380636;1200.0819373179525;1236.5857437758273;1246.1650694035018;1274.8916342110838;1320.2672516753294;28976;31157.221069473446;31563.84218078895;32017.519737204428;32177.678223217466;48011;48768;48778;48811.755941220064;48901.75549122231;387068;388136;388160.65750794346;388751;388947;26999.595006075317;27268.136845630925;27874.860625696743;27940.720592794623;29642;45708.31437528506;46353.53646463492;46443.53556464392;46649.28279046108;46923.296150557086;37637.2472550542;38951.6104838948;40310.1937961245;40612.39081413722;41402.37896431496;39989.600103998586;40060.19879602333;40929;41593.79203103965;41633;39263;39292.60707392889;39409.211815764094;39482;40151;33257;36392.45411318779;36720.81639591785;36972;37258.25483490261;27293;27764.722352776214;27774.44451111007;27812.58281125744;27825.860870695516 15:59:23 INFO - PROCESS | 31876 | |2;jquery.html;359.60623117686157;375.1184715917594;389.43920754114083;395.45032404957107;414.8091877736241;14101.2244326562;17738.91130544347;18862;19883.70174447381;20075.996200190006;15570.53288401347;19402;19426.90286548569;19608.803911960877;19707.113179906904;5371.785128594861;5649.830505084834;5659.717014149297;5660.886782264352;5662.575306851982;5399.568034557226;5550.500454959053;5578.135389014689;5614.410486898878;5640.661560306395;5579.05156123458;5586.162075688639;5586.944130558703;5590.496855283024;5592;5444.373897001856;5538.307711536058;5571.693556854371;5577.69322687252;5609.35492418373;4990.351254336949;4991.750412479398;4993.550580447777;4995.400551933755;4996.675216110977;22930.54138917229;22933.426664333358;23046.884765576233;23099.53800923972;23308;2257.5484903019296;2462.655228268044;2498.4253621667117;2812.6484189476314;2891.7831162662674;13862.514811981533;14495.782563261559;14645.780313295309;14668.633284167874;15056.32246548908;1825.3063835742332;1873.990630046841;1881.1534809335717;1882.2753240002698;1963.1656545967905;79710;80919.59540202262;83176.58411707963;83233;83434;9482;9782.608695652094;9839.262055345878;9852.408855468695;10200.3369780964;13409.865901340861;13514.932425338007;13515.324233788271;13530.661733456545;13556.389962451713;7375.704971801175;7775.105862825734;7809.726659566888;7947.721829735931;7972.123066462685;7538.962305188439;7555.395568354628;7794.830775383624;7852.254035866584;7889.526628402315;6090.573659843857;6148.661823599745;6196.690165491707;6271.498280137578;6323.841903952346;1738.356807981055;1785.366195000782;1809.7556829828068;1821.435355039942;1840.3988529978737;929.3680297397699;977.5161295158799;1023.4217667018111;1052.9315594486334;1090.6128324444867;18061;19289.80710192908;19420.611587768166;19505.804941950682;19587;956.6986399284342;961.3895176562933;1003.1673710820002;1005.8742657167854;1056.8361903904906;10448.32085914413;11015.779684406427;11023.94284323115;11212.66362009141;11287.54849806015;427830;435762;435908;436072;437038;80880.59559702165;81084.59457702674;82454.58772706098;84080;84260.57869710735;971.6113554578226;987.6592575561419;1011.0243614911645;1013.0780989299702;1018.4042335233971;16562.337506499603;16564.83435165633;17038.14809259557;17424.390146344816;17481.47555573335;38121;38983.41524877186;39308.41037384384;40761;41308.58691413047;48057;49282.75358623184;49296.507034930626;49892.501074990236;50472;5874.765009399576;8392.20274073962;8398.328133749163;8567.4002819802;8641.56792160377;12922.483100675867;13335.66660833487;13460.596182114552;13522.661933451742;14086.084404513675;8692.304615630608;11762.82355764647;11875.406229688653;12496.937515312366;12528.74942501163;5410.59420543453;6964.616946067812;9231.661409095777;10451.372917624967;11459.08327333828;11147.442627868411;11296.60461883863;11303;11710.824337635113;12284.877151228373;7321.59731214767;7610.695572177051;7614.16244213125;7953;8587.05542390349;8373.190198459564;10979.39613321275;11447;11912.868277513622;11913.344766037604 15:59:23 INFO - PROCESS | 31876 | |3;mootools.html;1329.295473399099;1447.811784468019;1495.8578935001176;1601.5195441367594;1789.6331252093314;7532.849343013137;8093.4334596578265;8475.491470511766;8873.556322183897;8889.066648001964;8910.46537207768;8973.461592304466;8982.640694372218;8983.685571005017;8998.235150012257;2326.941826454335;2330.673705681206;2352.9764702352927;2353.2469609724894;2362.173239366225;2286.8399212055165;2295.162265772989;2339.7192336919593;2339.847909885852;2344.132670911765;2298.230092918874;2315.097112126272;2340.707411573553;2346.732764307272;2347.0377145370403;2265.6148454762647;2273.27255278311;2274.5337205872756;2274.636058230675;2291.186628746804;2189.397915573214;2218.1238410827796;2246.0791075658904;2264.9886750566143;2270.852394594346;8434.198751118694;8502.617382217753;8507.787305317355;8510;8515.233628973423;5056.216286475602;5273.947260527384;5277.102892508245;5339.012282727708;5340.465953404668;3299.4390953537954;3451.861925523001;3493.825308734553;3504.3692135415613;3512.4204506256438;1046.712154157605;1076.208986394999;1086.7935092332511;1105.287089827065;1118.558169523034;12045.518179272818;12053.15627906047;12130.211536250115;12149.514019439212;12238.938805306005;4151.7924103794685;4310.676699247569;4418.801153948016;4512.390827238347;4547.3179023146795;9549.761255968657;9559.283053770994;9562;9562.521873906277;9584.28117891161;5073.8731531712;5075.923861142086;5077.644564880497;5078.923816142761;5078.97460512695;4018.517777866675;4237.682173836913;4327.82688692455;4379.64962802975;4792.808287668524;3252.349530093991;3651.068977410743;3664.9633503665154;3674.41209406499;3701.851925922933;1067.9092277156374;1090.6400887707039;1107.0921844087932;1129.3506233915532;1136.9260998035102;779.563444471098;873.2315562305258;892.5403417240127;914.1544071733753;915.5788337364753;5638.449426407688;5648.15277708347;5654.688992105392;5711.685857277889;5834.416558344132;895.2927187521915;898.4654130792168;905.9864102038474;938.15565990609;965.6089283840056;3623.4927110204594;3737.906552336159;3747.138158223624;3784.4323351496946;3819.2170605025904;10448.425336606408;10884.183686223407;10892.61875834342;11088.279261847954;11200.551977920792;11054.22620416563;11059.557617695202;11079.944600277107;11084.445777711082;11115.110791136689;8087.1912808719835;8352.373571982125;8353.54055526952;8355.874661879956;8363.330933525303;5381.381141168659;5605.383407825218;5641.351244606923;5666.858328541819;5803.593748437569;9907.504624768588;9918.454485003105;9990.050945160197;10100.646477373257;10105;9360.11078947499;9921.851172232567;10269.691909242734;10396.532156052845;10441.112505437122;7257.455690823315;7743.651535680795;7846.686132554634;7932.127465978622;8135.7152499662225;8220.958895205486;8315.459495132796;8331.416800823885;8368.24685778283;8441.620127094418;7737.264959828807;7775.300222979959;7798.454108212371;7801.29788319054;7928.801779955316;7761.223877612194;7774.844503109793;7778.377729781717;7828.256315650232;7870.72452464161;6525.608463492205;6686.231083425469;6687.063811066552;6699.698513566998;6761.695723692544;4767.404074490689;4783.569478746929;4803.183458811941;4855.271709243641;4944.134776414185;4837;4854.781534831011;4895.902081958412;4906.018796240837;4913.926291105708 15:59:23 INFO - PROCESS | 31876 | |4;prototype.html;326.8333150093453;332.1662626806715;336.17133765268625;338.54465743574883;343.91402149462635;5203.843884683461;5316.20256961456;5318.521333080022;5323.627346085776;5347;4984.25236214568;5057.659720174157;5110.770015349318;5174;5271.209318602202;4139.130782535656;4144.9585504145025;4155.272827255233;4166.395872598471;4169.498980367065;4130.710850240484;4143;4157.168566286739;4159.667226621863;4181.184668989548;3824.388097904336;3907.785071821049;4110.260153172428;4112.979435102835;4121.814518346667;4114.670826333886;4125.834966601333;4127.587241275864;4127.8968025799295;4131.35963925592;3661.377565813818;3661.7253705972157;3662.267546490686;3668.743187976843;3675.7426980111404;5022.924656130161;5058.924116138261;5061.468545802674;5131.384233891919;5146.433892271847;4647.581717645428;4648.83729069481;4667.6732628716;4669.159551280768;4684.250519916831;5486.506214440679;5487;5494.203340515603;5513.393526712059;5529.806456774034;3724.6089160638285;3924.2543916655745;3996.6403023728003;4009.8997525061814;4023.2154729827694;5636.718164091779;5653.689047102408;5658.519025882805;5664.716764161775;5677.801276955328;3959.940600890989;3964.4053391990856;3971.7021223407783;3980.9848488635794;3994.7802870842384;3925.3130702127014;4025.215083058834;4085.836566537365;4089.488813898263;4100.569440208795;4581.610563102107;4587.954120458753;4598.908021839545;4618.930716039262;4641.675082744243;5463.371712253142;5473.31583552056;5490.313710786152;5521.889562208814;5572.498475137257;1397.4969011155977;1405.248192217159;1424.1953296387715;1424.2736204535554;1426.664733787559;883.5096521430668;891.6700820696384;894.0299774744345;899.5187574647532;905.7101727447285;4764.332993380929;4773.498782627844;4782.3457980820285;4799.6400269979895;4799.832005879777;4963.851084467472;4998.750062496933;5018;5019.573336266385;5020.7740651670765;4755.144074066701;4781.6891902026255;4974.0797952378925;5363.81226657073;5442.2380866678695;2986.596809430699;3004.654464736583;3004.9699503005127;3007.323352245727;3017.0087742090113;5670.829875103754;5874.089516125007;5891.175235467038;5905.734241959122;5920.555958303058;5726.99777538929;5734.741936612862;5746.712664366765;5753.88492230153;5763.279590051244;5797.768089276382;5827.970860145672;5830.62100963436;5838.328592211865;5842.824715258549;5399.974004939049;5605.495505404533;5610.382857885547;5612.5509959202345;5651.57613179005;5529.778808847762;5539.19681646151;5540.584456165884;5540.889182216252;5542.722863856871;5763.279590051244;5774.971125144348;5797.826065218051;5851.069147181505;5874.765009399747;3842.193139440749;4046.858359957387;4054.1891621676373;4103.425520427083;4116.567760385236;5333.799895023589;5454.645448045864;5527.50252477279;5685.488306052475;5714.285714285715;5511.421300763523;5511.944880551143;5590.385057643574;5597.720113994365;5608.242887210175;5499.340079190522;5518.91721624184;5536.446355364431;5558.804856955774;5611.775528978795;5238.895222095613;5493.368262649687;5506.614536982373;5513.145462453326;5520.227168196376;5537.169424586345;5559.16612508127;5563.77744890216;5579.74891129892;5645.153227015816;5158.561522270649;5390.2481693449;5440.292761940922;5452.945470545403;5523.861903452445 15:59:23 INFO - PROCESS | 31876 | |5;yui.html;726.5386479585463;736.2784471218206;776.1617453150596;797.4856217739562;822.4695071678764;15362.539123826275;16885.831141688563;16908.1545922704;16965.660686786257;16986.150692465373;15236.085834849917;15384.692306153871;15410;15426.768598471032;15455.149966751824;636.6084857812435;653.3107571512053;771.6373304546854;783.3576467296818;809.76112046946;802.3941923847487;804.4770898915693;804.5896592737687;806.5846089264033;806.6410447350931;846.1622993236713;847.6100993542977;847.7795773099008;849.3757088539918;849.6856163219618;675.5305062981206;679.5514960126293;697.435077587151;698.2005603583868;839.9244068033876;601.1073555769678;617.6448542088317;618.6442795392667;620.6586377492406;622.3154530016946;15016.774748378783;15029.774553381709;15035.699286014327;15046.172460514666;15086.849131508654;14009.71980560383;14163.716725665532;14273.286335683279;14300.642483937881;14375.78436323456;12538.623841284776;12630.873691263061;12686;12886.226826390353;13398.598042058753;4355.7822108894425;4380.452443444569;4424.6239069678795;4448.6885917985755;4504.702601673171;77643.61178194072;78724.99999999943;80174;80587;80814.59592702058;10863.83704244437;10925.945370273097;11120.55517779296;11199.328040317607;12075;21090.156393744386;21204.469888252916;21237.575248494944;21266.36200913975;21270.893645531673;8122.959385203154;8147.348212143014;8210.917890821134;8309.875351869727;8359.247667709935;4536.6824322297125;4648.395708557866;4693.78877950493;4697.342372067913;4776.522347765195;3502.1594817243677;3620.2759448109955;3625.637436256406;3636.2363903580545;3640.2719456108885;602.3795988790228;607.60505671314;616.0758861707438;624.5815303746501;624.950003999679;649.5875119299279;675.4495086504581;683.1017212365723;691.7647999680133;697.9232284448808;9212.447253164832;9235.399699019543;9318.580663870142;9506.47714375716;9686.951565242269;575.7294071786254;595.7706283080959;618.9910445973035;694.3230350408412;700.7351730127169;4810.95189048105;4925.261210818406;4953.925691114636;4963.875903102379;5048;107625;109126.45436772765;109723;112461.43769281101;113632.43183784194;90814.54592726994;91989.54005230065;92444.53777731069;95538;97634;83460;84243.57878210692;86949.56525217333;87051.56474217716;89832;9035.864462032943;9213.170814626448;9401.320623622234;9462.952685236529;9476.384035037701;49750.502494974586;49970;50827.2375914369;51257.46227613177;51698.74150629223;67237.66381168063;73595;74153.25846741463;76257.23742762502;77563.61218194099;12092.697682558006;12108.334041627795;12141.210821296587;12245.326507041842;12705.745885082431;13152.551773316532;13388.19670819754;13408.995167885021;13738.725225495635;13808.723825523231;11334;11724.58963936258;12061.457234424297;12141.817872732092;12422.751544968869;11507.482163302839;12041.458134383798;12069.75860482803;12175.39123043862;12186.086043546593;12365;12428.13003089775;12555.937220314205;13298.468061277828;13301.135426197266;9551.379160354554;9606.27952903521;10106.545205465625;10134.290599657954;10491.895081049397;9549.665761698307;10446.582136714751;10543.103836174012;10573.101286390744;10574.15406767472 15:59:23 INFO - PROCESS | 31876 | __end_tp_report 15:59:23 INFO - PROCESS | 31876 | __start_cc_report 15:59:23 INFO - PROCESS | 31876 | _x_x_mozilla_cycle_collect,1314 15:59:23 INFO - PROCESS | 31876 | __end_cc_report 15:59:23 INFO - PROCESS | 31876 | __startTimestamp1476485963487__endTimestamp 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | ------- Summary: start ------- 15:59:23 INFO - PROCESS | 31876 | Number of tests: 6 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#0] dojo.html Cycles:165 Average:5309.20 Median:5493.04 stddev:610.52 (11.1%) stddev-sans-first:584.82 15:59:23 INFO - PROCESS | 31876 | Values: 2996.5 3050.5 3079.2 3097.7 3100.7 5096.6 5211.0 5257.3 5331.0 5404.1 5224.3 5236.6 5274.1 5277.2 5285.8 4738.3 4779.3 4792.6 4821.1 4841.5 4814.6 4827.1 4849.5 4850.4 4856.8 4887.2 4915.4 4923.8 4932.7 4951.6 4991.1 5008.3 5009.2 5011.3 5070.3 4649.6 4678.7 4811.9 4914.8 4973.1 5300.0 5304.1 5314.0 5319.1 5416.1 5739.5 5743.8 5744.9 5745.9 5747.0 5494.8 5540.6 5729.7 5785.4 5792.4 5362.2 5406.1 5407.8 5465.1 5477.5 5906.5 5909.2 5910.0 5910.1 5920.1 5346.8 5388.1 5629.5 5675.2 5680.4 5687.1 5719.5 5735.7 5747.3 5826.4 5690.3 5722.1 5734.9 5739.3 5809.2 5489.3 5731.3 5747.4 5751.4 5752.3 4265.3 4279.1 4309.2 4317.1 4344.4 3886.9 3911.7 3952.5 3957.6 3958.0 5469.4 5480.0 5486.8 5493.0 5560.3 5763.9 5771.7 5771.8 5772.0 5775.8 5120.1 5228.3 5400.2 5432.8 5457.5 4972.7 4981.1 4981.6 4985.0 4990.8 5958.3 5969.6 5971.5 5978.2 6037.2 5478.8 5499.7 5531.6 5619.6 5743.6 5790.7 5877.1 5892.1 5952.6 6060.0 5604.2 5653.6 5661.9 5668.5 5724.6 5684.8 5706.5 5727.9 5729.4 5741.1 5280.4 5303.9 5533.1 5593.7 5598.4 5561.0 5582.4 5587.8 5642.0 5688.8 5602.0 5603.2 5606.6 5618.1 5627.7 5357.3 5627.5 5674.4 5675.1 5681.5 5325.7 5481.2 5495.0 5522.6 5591.3 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#1] ext.html Cycles:180 Average:57245.18 Median:20345.45 stddev:112707.27 (554.0%) stddev-sans-first:112943.12 15:59:23 INFO - PROCESS | 31876 | Values: 570.7 608.1 628.1 630.9 642.4 31428.7 42209.6 42490.8 42743.0 42804.6 44044.6 44428.0 44428.8 44503.8 44508.8 7968.7 8170.3 8760.6 8915.5 8919.2 10379.7 10720.9 10728.0 11001.9 11019.7 10809.7 10918.5 10921.2 10958.7 10964.6 10713.1 10772.2 10784.6 10790.5 10802.5 10589.7 10638.9 10653.3 10657.1 10658.1 40816.4 41122.4 41134.4 41137.6 41153.4 3522.7 3544.2 3561.8 3685.2 3718.9 30936.2 31046.8 31154.5 31811.2 31953.7 4211.3 4236.2 4303.2 4303.6 4345.6 322888.4 323333.0 323690.0 324252.0 324667.0 54787.0 55400.2 55547.4 55624.0 55698.2 58737.4 58866.2 58978.0 59023.6 59088.0 15997.4 16250.8 16423.3 16671.9 16690.6 10610.6 10697.6 12255.0 12531.9 12543.8 18377.3 18401.6 18431.4 18721.7 18822.7 7044.2 7046.4 7085.2 7190.2 7334.2 918.1 969.4 996.8 1012.7 1059.3 31170.4 31422.4 31422.8 31908.0 31958.5 1079.3 1087.1 1130.2 1130.2 1147.0 16150.4 16406.8 16570.7 16674.3 16699.0 531115.0 532704.0 537580.0 538332.0 539395.0 37571.4 38003.6 38009.8 38056.2 38061.6 1200.1 1236.6 1246.2 1274.9 1320.3 28976.0 31157.2 31563.8 32017.5 32177.7 48011.0 48768.0 48778.0 48811.8 48901.8 387068.0 388136.0 388160.7 388751.0 388947.0 26999.6 27268.1 27874.9 27940.7 29642.0 45708.3 46353.5 46443.5 46649.3 46923.3 37637.2 38951.6 40310.2 40612.4 41402.4 39989.6 40060.2 40929.0 41593.8 41633.0 39263.0 39292.6 39409.2 39482.0 40151.0 33257.0 36392.5 36720.8 36972.0 37258.3 27293.0 27764.7 27774.4 27812.6 27825.9 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#2] jquery.html Cycles:180 Average:26773.34 Median:19851.93 stddev:71726.96 (361.3%) stddev-sans-first:71900.75 15:59:23 INFO - PROCESS | 31876 | Values: 359.6 375.1 389.4 395.5 414.8 14101.2 17738.9 18862.0 19883.7 20076.0 15570.5 19402.0 19426.9 19608.8 19707.1 5371.8 5649.8 5659.7 5660.9 5662.6 5399.6 5550.5 5578.1 5614.4 5640.7 5579.1 5586.2 5586.9 5590.5 5592.0 5444.4 5538.3 5571.7 5577.7 5609.4 4990.4 4991.8 4993.6 4995.4 4996.7 22930.5 22933.4 23046.9 23099.5 23308.0 2257.5 2462.7 2498.4 2812.6 2891.8 13862.5 14495.8 14645.8 14668.6 15056.3 1825.3 1874.0 1881.2 1882.3 1963.2 79710.0 80919.6 83176.6 83233.0 83434.0 9482.0 9782.6 9839.3 9852.4 10200.3 13409.9 13514.9 13515.3 13530.7 13556.4 7375.7 7775.1 7809.7 7947.7 7972.1 7539.0 7555.4 7794.8 7852.3 7889.5 6090.6 6148.7 6196.7 6271.5 6323.8 1738.4 1785.4 1809.8 1821.4 1840.4 929.4 977.5 1023.4 1052.9 1090.6 18061.0 19289.8 19420.6 19505.8 19587.0 956.7 961.4 1003.2 1005.9 1056.8 10448.3 11015.8 11023.9 11212.7 11287.5 427830.0 435762.0 435908.0 436072.0 437038.0 80880.6 81084.6 82454.6 84080.0 84260.6 971.6 987.7 1011.0 1013.1 1018.4 16562.3 16564.8 17038.1 17424.4 17481.5 38121.0 38983.4 39308.4 40761.0 41308.6 48057.0 49282.8 49296.5 49892.5 50472.0 5874.8 8392.2 8398.3 8567.4 8641.6 12922.5 13335.7 13460.6 13522.7 14086.1 8692.3 11762.8 11875.4 12496.9 12528.7 5410.6 6964.6 9231.7 10451.4 11459.1 11147.4 11296.6 11303.0 11710.8 12284.9 7321.6 7610.7 7614.2 7953.0 8587.1 8373.2 10979.4 11447.0 11912.9 11913.3 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#3] mootools.html Cycles:180 Average:5672.49 Median:4875.59 stddev:3286.54 (67.4%) stddev-sans-first:3279.55 15:59:23 INFO - PROCESS | 31876 | Values: 1329.3 1447.8 1495.9 1601.5 1789.6 7532.8 8093.4 8475.5 8873.6 8889.1 8910.5 8973.5 8982.6 8983.7 8998.2 2326.9 2330.7 2353.0 2353.2 2362.2 2286.8 2295.2 2339.7 2339.8 2344.1 2298.2 2315.1 2340.7 2346.7 2347.0 2265.6 2273.3 2274.5 2274.6 2291.2 2189.4 2218.1 2246.1 2265.0 2270.9 8434.2 8502.6 8507.8 8510.0 8515.2 5056.2 5273.9 5277.1 5339.0 5340.5 3299.4 3451.9 3493.8 3504.4 3512.4 1046.7 1076.2 1086.8 1105.3 1118.6 12045.5 12053.2 12130.2 12149.5 12238.9 4151.8 4310.7 4418.8 4512.4 4547.3 9549.8 9559.3 9562.0 9562.5 9584.3 5073.9 5075.9 5077.6 5078.9 5079.0 4018.5 4237.7 4327.8 4379.6 4792.8 3252.3 3651.1 3665.0 3674.4 3701.9 1067.9 1090.6 1107.1 1129.4 1136.9 779.6 873.2 892.5 914.2 915.6 5638.4 5648.2 5654.7 5711.7 5834.4 895.3 898.5 906.0 938.2 965.6 3623.5 3737.9 3747.1 3784.4 3819.2 10448.4 10884.2 10892.6 11088.3 11200.6 11054.2 11059.6 11079.9 11084.4 11115.1 8087.2 8352.4 8353.5 8355.9 8363.3 5381.4 5605.4 5641.4 5666.9 5803.6 9907.5 9918.5 9990.1 10100.6 10105.0 9360.1 9921.9 10269.7 10396.5 10441.1 7257.5 7743.7 7846.7 7932.1 8135.7 8221.0 8315.5 8331.4 8368.2 8441.6 7737.3 7775.3 7798.5 7801.3 7928.8 7761.2 7774.8 7778.4 7828.3 7870.7 6525.6 6686.2 6687.1 6699.7 6761.7 4767.4 4783.6 4803.2 4855.3 4944.1 4837.0 4854.8 4895.9 4906.0 4913.9 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#4] prototype.html Cycles:180 Average:4596.51 Median:5152.50 stddev:1347.40 (26.2%) stddev-sans-first:1312.51 15:59:23 INFO - PROCESS | 31876 | Values: 326.8 332.2 336.2 338.5 343.9 5203.8 5316.2 5318.5 5323.6 5347.0 4984.3 5057.7 5110.8 5174.0 5271.2 4139.1 4145.0 4155.3 4166.4 4169.5 4130.7 4143.0 4157.2 4159.7 4181.2 3824.4 3907.8 4110.3 4113.0 4121.8 4114.7 4125.8 4127.6 4127.9 4131.4 3661.4 3661.7 3662.3 3668.7 3675.7 5022.9 5058.9 5061.5 5131.4 5146.4 4647.6 4648.8 4667.7 4669.2 4684.3 5486.5 5487.0 5494.2 5513.4 5529.8 3724.6 3924.3 3996.6 4009.9 4023.2 5636.7 5653.7 5658.5 5664.7 5677.8 3959.9 3964.4 3971.7 3981.0 3994.8 3925.3 4025.2 4085.8 4089.5 4100.6 4581.6 4588.0 4598.9 4618.9 4641.7 5463.4 5473.3 5490.3 5521.9 5572.5 1397.5 1405.2 1424.2 1424.3 1426.7 883.5 891.7 894.0 899.5 905.7 4764.3 4773.5 4782.3 4799.6 4799.8 4963.9 4998.8 5018.0 5019.6 5020.8 4755.1 4781.7 4974.1 5363.8 5442.2 2986.6 3004.7 3005.0 3007.3 3017.0 5670.8 5874.1 5891.2 5905.7 5920.6 5727.0 5734.7 5746.7 5753.9 5763.3 5797.8 5828.0 5830.6 5838.3 5842.8 5400.0 5605.5 5610.4 5612.6 5651.6 5529.8 5539.2 5540.6 5540.9 5542.7 5763.3 5775.0 5797.8 5851.1 5874.8 3842.2 4046.9 4054.2 4103.4 4116.6 5333.8 5454.6 5527.5 5685.5 5714.3 5511.4 5511.9 5590.4 5597.7 5608.2 5499.3 5518.9 5536.4 5558.8 5611.8 5238.9 5493.4 5506.6 5513.1 5520.2 5537.2 5559.2 5563.8 5579.7 5645.2 5158.6 5390.2 5440.3 5452.9 5523.9 15:59:23 INFO - PROCESS | 31876 | 15:59:23 INFO - PROCESS | 31876 | [#5] yui.html Cycles:180 Average:20413.99 Median:27357.19 stddev:29337.41 (107.2%) stddev-sans-first:29382.46 15:59:23 INFO - PROCESS | 31876 | Values: 726.5 736.3 776.2 797.5 822.5 15362.5 16885.8 16908.2 16965.7 16986.2 15236.1 15384.7 15410.0 15426.8 15455.1 636.6 653.3 771.6 783.4 809.8 802.4 804.5 804.6 806.6 806.6 846.2 847.6 847.8 849.4 849.7 675.5 679.6 697.4 698.2 839.9 601.1 617.6 618.6 620.7 622.3 15016.8 15029.8 15035.7 15046.2 15086.8 14009.7 14163.7 14273.3 14300.6 14375.8 12538.6 12630.9 12686.0 12886.2 13398.6 4355.8 4380.5 4424.6 4448.7 4504.7 77643.6 78725.0 80174.0 80587.0 80814.6 10863.8 10925.9 11120.6 11199.3 12075.0 21090.2 21204.5 21237.6 21266.4 21270.9 8123.0 8147.3 8210.9 8309.9 8359.2 4536.7 4648.4 4693.8 4697.3 4776.5 3502.2 3620.3 3625.6 3636.2 3640.3 602.4 607.6 616.1 624.6 625.0 649.6 675.4 683.1 691.8 697.9 9212.4 9235.4 9318.6 9506.5 9687.0 575.7 595.8 619.0 694.3 700.7 4811.0 4925.3 4953.9 4963.9 5048.0 107625.0 109126.5 109723.0 112461.4 113632.4 90814.5 91989.5 92444.5 95538.0 97634.0 83460.0 84243.6 86949.6 87051.6 89832.0 9035.9 9213.2 9401.3 9463.0 9476.4 49750.5 49970.0 50827.2 51257.5 51698.7 67237.7 73595.0 74153.3 76257.2 77563.6 12092.7 12108.3 12141.2 12245.3 12705.7 13152.6 13388.2 13409.0 13738.7 13808.7 11334.0 11724.6 12061.5 12141.8 12422.8 11507.5 12041.5 12069.8 12175.4 12186.1 12365.0 12428.1 12555.9 13298.5 13301.1 9551.4 9606.3 10106.5 10134.3 10491.9 9549.7 10446.6 10543.1 10573.1 10574.2 15:59:23 INFO - PROCESS | 31876 | -------- Summary: end -------- 15:59:23 INFO - PROCESS | 31876 | 15:59:24 INFO - TEST-INFO | 31876: exit 0 15:59:24 INFO - TEST-OK | dromaeo_css | took 1117878ms 15:59:24 INFO - TEST-START | kraken 15:59:24 INFO - operating with platform_type : linux_ 15:59:24 INFO - Initialising browser for kraken test... 15:59:24 INFO - TEST-INFO | started process 32190 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9yVNnw/profile http://localhost:48641/getInfo.html) 15:59:28 INFO - PROCESS | 32190 | __metrics Screen width/height:1600/1200 15:59:28 INFO - PROCESS | 32190 | colorDepth:24 15:59:28 INFO - PROCESS | 32190 | Browser inner width/height: 1024/697 15:59:28 INFO - PROCESS | 32190 | __metrics 15:59:29 INFO - PROCESS | 32190 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:59:29 INFO - PROCESS | 32190 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:30 INFO - TEST-INFO | 32190: exit 0 15:59:30 INFO - Browser initialized. 15:59:30 INFO - Running cycle 1/1 for kraken test... 15:59:30 INFO - TEST-INFO | started process 32272 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9yVNnw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:42 INFO - PROCESS | 32272 | RSS: Main: 171630592 15:59:42 INFO - PROCESS | 32272 | 15:59:45 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48641/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:45 INFO - PROCESS | 32272 | RSS: Main: 237174784 15:59:45 INFO - PROCESS | 32272 | 15:59:48 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48641/tests/kraken/driver.html?testName=audio-dft) 15:59:49 INFO - PROCESS | 32272 | RSS: Main: 215576576 15:59:49 INFO - PROCESS | 32272 | 15:59:52 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48641/tests/kraken/driver.html?testName=audio-fft) 15:59:52 INFO - PROCESS | 32272 | RSS: Main: 297119744 15:59:52 INFO - PROCESS | 32272 | 15:59:55 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48641/tests/kraken/driver.html?testName=audio-oscillator) 15:59:55 INFO - PROCESS | 32272 | RSS: Main: 205856768 15:59:55 INFO - PROCESS | 32272 | 15:59:57 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48641/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:58 INFO - PROCESS | 32272 | RSS: Main: 211058688 15:59:58 INFO - PROCESS | 32272 | 16:00:01 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48641/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:01 INFO - PROCESS | 32272 | RSS: Main: 247312384 16:00:01 INFO - PROCESS | 32272 | 16:00:05 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48641/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:05 INFO - PROCESS | 32272 | RSS: Main: 246435840 16:00:05 INFO - PROCESS | 32272 | 16:00:08 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48641/tests/kraken/driver.html?testName=json-parse-financial) 16:00:08 INFO - PROCESS | 32272 | RSS: Main: 246276096 16:00:08 INFO - PROCESS | 32272 | 16:00:10 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48641/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:11 INFO - PROCESS | 32272 | RSS: Main: 208154624 16:00:11 INFO - PROCESS | 32272 | 16:00:12 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:13 INFO - PROCESS | 32272 | RSS: Main: 215281664 16:00:13 INFO - PROCESS | 32272 | 16:00:15 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:16 INFO - PROCESS | 32272 | RSS: Main: 225529856 16:00:16 INFO - PROCESS | 32272 | 16:00:18 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:19 INFO - PROCESS | 32272 | RSS: Main: 233566208 16:00:19 INFO - PROCESS | 32272 | 16:00:22 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:22 INFO - PROCESS | 32272 | RSS: Main: 230436864 16:00:22 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | Cycle 1(1): loaded http://localhost:48641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48641/tests/kraken/driver.html?testName=ai-astar) 16:00:24 INFO - PROCESS | 32272 | RSS: Main: 282435584 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | __start_tp_report 16:00:24 INFO - PROCESS | 32272 | _x_x_mozilla_page_load 16:00:24 INFO - PROCESS | 32272 | _x_x_mozilla_page_load_details 16:00:24 INFO - PROCESS | 32272 | |i|pagename|runs| 16:00:24 INFO - PROCESS | 32272 | |0;ai-astar;104;111;111;110;110;111;112;110;110;110 16:00:24 INFO - PROCESS | 32272 | |1;audio-beat-detection;143;120;122;158;125;136;122;122;152;126 16:00:24 INFO - PROCESS | 32272 | |2;audio-dft;162;161;161;161;161;162;162;161;162;161 16:00:24 INFO - PROCESS | 32272 | |3;audio-fft;76;74;75;108;77;75;76;76;121;78 16:00:24 INFO - PROCESS | 32272 | |4;audio-oscillator;83;83;110;83;83;83;104;84;84;114 16:00:24 INFO - PROCESS | 32272 | |5;imaging-gaussian-blur;117;116;116;117;117;116;116;117;117;117 16:00:24 INFO - PROCESS | 32272 | |6;imaging-darkroom;145;146;146;144;146;146;146;146;145;145 16:00:24 INFO - PROCESS | 32272 | |7;imaging-desaturate;84;84;84;84;84;84;84;84;85;84 16:00:24 INFO - PROCESS | 32272 | |8;json-parse-financial;51;50;52;51;51;54;53;53;52;52 16:00:24 INFO - PROCESS | 32272 | |9;json-stringify-tinderbox;58;59;59;59;59;60;60;61;61;61 16:00:24 INFO - PROCESS | 32272 | |10;stanford-crypto-aes;90;81;79;79;80;80;81;80;86;80 16:00:24 INFO - PROCESS | 32272 | |11;stanford-crypto-ccm;131;134;139;143;131;134;132;133;170;132 16:00:24 INFO - PROCESS | 32272 | |12;stanford-crypto-pbkdf2;177;168;169;165;168;169;167;170;167;170 16:00:24 INFO - PROCESS | 32272 | |13;stanford-crypto-sha256-iterative;67;67;65;65;67;68;66;65;66;66 16:00:24 INFO - PROCESS | 32272 | __end_tp_report 16:00:24 INFO - PROCESS | 32272 | __start_cc_report 16:00:24 INFO - PROCESS | 32272 | _x_x_mozilla_cycle_collect,1706 16:00:24 INFO - PROCESS | 32272 | __end_cc_report 16:00:24 INFO - PROCESS | 32272 | __startTimestamp1476486024816__endTimestamp 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | ------- Summary: start ------- 16:00:24 INFO - PROCESS | 32272 | Number of tests: 14 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:2.18 (2.0%) stddev-sans-first:0.73 16:00:24 INFO - PROCESS | 32272 | Values: 104.0 111.0 111.0 110.0 110.0 111.0 112.0 110.0 110.0 110.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:131.00 stddev:13.90 (10.6%) stddev-sans-first:14.22 16:00:24 INFO - PROCESS | 32272 | Values: 143.0 120.0 122.0 158.0 125.0 136.0 122.0 122.0 152.0 126.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 16:00:24 INFO - PROCESS | 32272 | Values: 162.0 161.0 161.0 161.0 161.0 162.0 162.0 161.0 162.0 161.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:16.61 (21.9%) stddev-sans-first:17.39 16:00:24 INFO - PROCESS | 32272 | Values: 76.0 74.0 75.0 108.0 77.0 75.0 76.0 76.0 121.0 78.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:83.00 stddev:12.81 (15.4%) stddev-sans-first:13.25 16:00:24 INFO - PROCESS | 32272 | Values: 83.0 83.0 110.0 83.0 83.0 83.0 104.0 84.0 84.0 114.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:00:24 INFO - PROCESS | 32272 | Values: 117.0 116.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 16:00:24 INFO - PROCESS | 32272 | Values: 145.0 146.0 146.0 144.0 146.0 146.0 146.0 146.0 145.0 145.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:0.32 (0.4%) stddev-sans-first:0.33 16:00:24 INFO - PROCESS | 32272 | Values: 84.0 84.0 84.0 84.0 84.0 84.0 84.0 84.0 85.0 84.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.20 (2.3%) stddev-sans-first:1.22 16:00:24 INFO - PROCESS | 32272 | Values: 51.0 50.0 52.0 51.0 51.0 54.0 53.0 53.0 52.0 52.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:1.06 (1.8%) stddev-sans-first:0.93 16:00:24 INFO - PROCESS | 32272 | Values: 58.0 59.0 59.0 59.0 59.0 60.0 60.0 61.0 61.0 61.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:80.50 stddev:3.57 (4.4%) stddev-sans-first:2.12 16:00:24 INFO - PROCESS | 32272 | Values: 90.0 81.0 79.0 79.0 80.0 80.0 81.0 80.0 86.0 80.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:134.00 stddev:11.91 (8.9%) stddev-sans-first:12.37 16:00:24 INFO - PROCESS | 32272 | Values: 131.0 134.0 139.0 143.0 131.0 134.0 132.0 133.0 170.0 132.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.00 stddev:3.20 (1.9%) stddev-sans-first:1.62 16:00:24 INFO - PROCESS | 32272 | Values: 177.0 168.0 169.0 165.0 168.0 169.0 167.0 170.0 167.0 170.0 16:00:24 INFO - PROCESS | 32272 | 16:00:24 INFO - PROCESS | 32272 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.05 16:00:24 INFO - PROCESS | 32272 | Values: 67.0 67.0 65.0 65.0 67.0 68.0 66.0 65.0 66.0 66.0 16:00:24 INFO - PROCESS | 32272 | -------- Summary: end -------- 16:00:24 INFO - PROCESS | 32272 | 16:00:26 INFO - TEST-INFO | 32272: exit 0 16:00:26 INFO - TEST-OK | kraken | took 61747ms 16:00:26 INFO - SUITE-END | took 1179s 16:00:26 INFO - Completed test suite (00:19:39) 16:00:26 INFO - javascript benchmark 16:00:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2996.5055765798643, 3050.5271682889133, 3079.184016235697, 3097.690230976901, 3100.7054329838656, 5096.643234973553, 5210.9739451302785, 5257.263983042377, 5330.973345133274, 5404.108322126849, 5224.29472021277, 5236.581073514119, 5274.103402421588, 5277.182036784295, 5285.841424757254, 4738.28925661151, 4779.330893674888, 4792.568668819806, 4821.108095002436, 4841.540053694911, 4814.63890208234, 4827.131116399047, 4849.490803465638, 4850.369451971239, 4856.7571621418965, 4887.218045112782, 4915.434725006634, 4923.82766603169, 4932.6793758405765, 4951.55436010759, 4991.051700176955, 5008.273800298973, 5009.2486127080865, 5011.32347133136, 5070.340855688773, 4649.628029757611, 4678.69588476748, 4811.855644330641, 4914.803407863681, 4973.079980203674, 5300.0, 5304.071787437183, 5314.0, 5319.122344813101, 5416.0792665247, 5739.540836733051, 5743.770249190027, 5744.913826292609, 5745.942540574582, 5746.971265143689, 5494.780208791643, 5540.556755459554, 5729.742161602738, 5785.421457854223, 5792.4497172768515, 5362.195670649395, 5406.135018397116, 5407.83776486706, 5465.098258787295, 5477.452254774491, 5906.49794767441, 5909.231799866076, 5909.970450147722, 5910.054391297372, 5920.141579470953, 5346.759395827197, 5388.0570900092325, 5629.521490673339, 5675.2054712340305, 5680.4319568042865, 5687.061634830289, 5719.48524632785, 5735.655860648374, 5747.252857128547, 5826.417358264224, 5690.345610254791, 5722.084466485426, 5734.856628584235, 5739.282589676291, 5809.157672137601, 5489.286392768994, 5731.340895797037, 5747.39652336507, 5751.396103409165, 5752.28096487939, 4265.274903266452, 4279.058607106432, 4309.245881970705, 4317.071829556598, 4344.391785150082, 3886.941695874564, 3911.706622003361, 3952.505936757905, 3957.6438120569287, 3957.980210098931, 5469.398366179717, 5479.972600136974, 5486.835394938158, 5493.038718224295, 5560.277163968658, 5763.88472230553, 5771.682557459296, 5771.797987070432, 5772.0, 5775.797847075332, 5120.078385890575, 5228.320318358589, 5400.243965844785, 5432.809851655173, 5457.536109430743, 4972.651914366032, 4981.103401387712, 4981.551660350513, 4985.0, 4990.775415106329, 5958.344582095966, 5969.611975221596, 5971.485262744315, 5978.222831032112, 6037.154798328237, 5478.753456094406, 5499.7250137492165, 5531.640443371168, 5619.634723742944, 5743.62666426681, 5790.681512516683, 5877.089051197071, 5892.086726557391, 5952.613080149777, 6059.969700151471, 5604.159376093618, 5653.575981801464, 5661.886762264814, 5668.461496157858, 5724.57065720081, 5684.77260909559, 5706.457886500776, 5727.8854422912145, 5729.369769325454, 5741.1388291756575, 5280.419153892992, 5303.893922121459, 5533.059379905345, 5593.692346920959, 5598.384177740364, 5560.97219513916, 5582.4138465462165, 5587.776488940397, 5642.0, 5688.77244910199, 5601.971990140023, 5603.2155498231095, 5606.579506536945, 5618.101103823368, 5627.690477023803, 5357.303550538561, 5627.465390787868, 5674.432556744293, 5675.091985282335, 5681.460261275172, 5325.680459172462, 5481.150421684645, 5495.038368285614, 5522.6134170607675, 5591.329040515164], "value": 5267.667651977828, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.6775671745463, 608.0544752909227, 628.0986783965011, 630.9148264984227, 642.3833120204604, 31428.685713142862, 42209.57790422099, 42490.78754606222, 42743.0, 42804.57195428041, 44044.55955440445, 44428.0, 44428.777856110755, 44503.77748111255, 44508.77745611275, 7968.721094761684, 8170.264676179143, 8760.605772740226, 8915.509646969414, 8919.152680495376, 10379.688609341732, 10720.892791072107, 10728.0, 11001.944990275077, 11019.724506887353, 10809.67570972868, 10918.50866710996, 10921.180911431635, 10958.671239862817, 10964.56141754329, 10713.08938740208, 10772.245942784008, 10784.622538211204, 10790.51442685081, 10802.51388687511, 10589.682309530726, 10638.893611063868, 10653.25427220095, 10657.147428205724, 10658.094062004739, 40816.387754183714, 41122.38316425256, 41134.38298425526, 41137.588624113974, 41153.382699259535, 3522.682958533744, 3544.220271540257, 3561.8041007744564, 3685.207680348711, 3718.9070273243387, 30936.226594335323, 31046.84476577625, 31154.532682009787, 31811.204719881956, 31953.680463195302, 4211.263028969918, 4236.237477254093, 4303.203907277164, 4303.634191093761, 4345.587169218919, 322888.38555807306, 323333.0, 323690.0, 324252.0, 324667.0, 54787.0, 55400.16899746507, 55547.44452555423, 55624.0, 55698.16452753212, 58737.41262587319, 58866.17007448917, 58978.0, 59023.62204724409, 59088.0, 15997.440089597041, 16250.837491625169, 16423.343066277215, 16671.91664041672, 16690.582735431468, 10610.628627997983, 10697.572097116185, 12255.0, 12531.93734031324, 12543.811842822364, 18377.26490940374, 18401.63196736058, 18431.354902578347, 18721.719174212398, 18822.71765923512, 7044.15470143586, 7046.401055910203, 7085.185203701641, 7190.209077001526, 7334.156571994289, 918.0727465260002, 969.3892847506168, 996.7956568903357, 1012.7063151686076, 1059.3273271472517, 31170.376592468023, 31422.37155256882, 31422.84288578588, 31908.0, 31958.532108266052, 1079.2930630437077, 1087.0922779479065, 1130.1580322659672, 1130.1862658885589, 1146.9541218351173, 16150.434734784461, 16406.753898691528, 16570.668586628442, 16674.333026678796, 16699.0, 531115.0, 532704.0, 537580.0, 538332.0, 539395.0, 37571.4364284536, 38003.619963800564, 38009.80995095007, 38056.238875222894, 38061.61938380636, 1200.0819373179525, 1236.5857437758273, 1246.1650694035018, 1274.8916342110838, 1320.2672516753294, 28976.0, 31157.221069473446, 31563.84218078895, 32017.519737204428, 32177.678223217466, 48011.0, 48768.0, 48778.0, 48811.755941220064, 48901.75549122231, 387068.0, 388136.0, 388160.65750794346, 388751.0, 388947.0, 26999.595006075317, 27268.136845630925, 27874.860625696743, 27940.720592794623, 29642.0, 45708.31437528506, 46353.53646463492, 46443.53556464392, 46649.28279046108, 46923.296150557086, 37637.2472550542, 38951.6104838948, 40310.1937961245, 40612.39081413722, 41402.37896431496, 39989.600103998586, 40060.19879602333, 40929.0, 41593.79203103965, 41633.0, 39263.0, 39292.60707392889, 39409.211815764094, 39482.0, 40151.0, 33257.0, 36392.45411318779, 36720.81639591785, 36972.0, 37258.25483490261, 27293.0, 27764.722352776214, 27774.44451111007, 27812.58281125744, 27825.860870695516], "value": 19816.39697659301, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.60623117686157, 375.1184715917594, 389.43920754114083, 395.45032404957107, 414.8091877736241, 14101.2244326562, 17738.91130544347, 18862.0, 19883.70174447381, 20075.996200190006, 15570.53288401347, 19402.0, 19426.90286548569, 19608.803911960877, 19707.113179906904, 5371.785128594861, 5649.830505084834, 5659.717014149297, 5660.886782264352, 5662.575306851982, 5399.568034557226, 5550.500454959053, 5578.135389014689, 5614.410486898878, 5640.661560306395, 5579.05156123458, 5586.162075688639, 5586.944130558703, 5590.496855283024, 5592.0, 5444.373897001856, 5538.307711536058, 5571.693556854371, 5577.69322687252, 5609.35492418373, 4990.351254336949, 4991.750412479398, 4993.550580447777, 4995.400551933755, 4996.675216110977, 22930.54138917229, 22933.426664333358, 23046.884765576233, 23099.53800923972, 23308.0, 2257.5484903019296, 2462.655228268044, 2498.4253621667117, 2812.6484189476314, 2891.7831162662674, 13862.514811981533, 14495.782563261559, 14645.780313295309, 14668.633284167874, 15056.32246548908, 1825.3063835742332, 1873.990630046841, 1881.1534809335717, 1882.2753240002698, 1963.1656545967905, 79710.0, 80919.59540202262, 83176.58411707963, 83233.0, 83434.0, 9482.0, 9782.608695652094, 9839.262055345878, 9852.408855468695, 10200.3369780964, 13409.865901340861, 13514.932425338007, 13515.324233788271, 13530.661733456545, 13556.389962451713, 7375.704971801175, 7775.105862825734, 7809.726659566888, 7947.721829735931, 7972.123066462685, 7538.962305188439, 7555.395568354628, 7794.830775383624, 7852.254035866584, 7889.526628402315, 6090.573659843857, 6148.661823599745, 6196.690165491707, 6271.498280137578, 6323.841903952346, 1738.356807981055, 1785.366195000782, 1809.7556829828068, 1821.435355039942, 1840.3988529978737, 929.3680297397699, 977.5161295158799, 1023.4217667018111, 1052.9315594486334, 1090.6128324444867, 18061.0, 19289.80710192908, 19420.611587768166, 19505.804941950682, 19587.0, 956.6986399284342, 961.3895176562933, 1003.1673710820002, 1005.8742657167854, 1056.8361903904906, 10448.32085914413, 11015.779684406427, 11023.94284323115, 11212.66362009141, 11287.54849806015, 427830.0, 435762.0, 435908.0, 436072.0, 437038.0, 80880.59559702165, 81084.59457702674, 82454.58772706098, 84080.0, 84260.57869710735, 971.6113554578226, 987.6592575561419, 1011.0243614911645, 1013.0780989299702, 1018.4042335233971, 16562.337506499603, 16564.83435165633, 17038.14809259557, 17424.390146344816, 17481.47555573335, 38121.0, 38983.41524877186, 39308.41037384384, 40761.0, 41308.58691413047, 48057.0, 49282.75358623184, 49296.507034930626, 49892.501074990236, 50472.0, 5874.765009399576, 8392.20274073962, 8398.328133749163, 8567.4002819802, 8641.56792160377, 12922.483100675867, 13335.66660833487, 13460.596182114552, 13522.661933451742, 14086.084404513675, 8692.304615630608, 11762.82355764647, 11875.406229688653, 12496.937515312366, 12528.74942501163, 5410.59420543453, 6964.616946067812, 9231.661409095777, 10451.372917624967, 11459.08327333828, 11147.442627868411, 11296.60461883863, 11303.0, 11710.824337635113, 12284.877151228373, 7321.59731214767, 7610.695572177051, 7614.16244213125, 7953.0, 8587.05542390349, 8373.190198459564, 10979.39613321275, 11447.0, 11912.868277513622, 11913.344766037604], "value": 8977.185219392415, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.295473399099, 1447.811784468019, 1495.8578935001176, 1601.5195441367594, 1789.6331252093314, 7532.849343013137, 8093.4334596578265, 8475.491470511766, 8873.556322183897, 8889.066648001964, 8910.46537207768, 8973.461592304466, 8982.640694372218, 8983.685571005017, 8998.235150012257, 2326.941826454335, 2330.673705681206, 2352.9764702352927, 2353.2469609724894, 2362.173239366225, 2286.8399212055165, 2295.162265772989, 2339.7192336919593, 2339.847909885852, 2344.132670911765, 2298.230092918874, 2315.097112126272, 2340.707411573553, 2346.732764307272, 2347.0377145370403, 2265.6148454762647, 2273.27255278311, 2274.5337205872756, 2274.636058230675, 2291.186628746804, 2189.397915573214, 2218.1238410827796, 2246.0791075658904, 2264.9886750566143, 2270.852394594346, 8434.198751118694, 8502.617382217753, 8507.787305317355, 8510.0, 8515.233628973423, 5056.216286475602, 5273.947260527384, 5277.102892508245, 5339.012282727708, 5340.465953404668, 3299.4390953537954, 3451.861925523001, 3493.825308734553, 3504.3692135415613, 3512.4204506256438, 1046.712154157605, 1076.208986394999, 1086.7935092332511, 1105.287089827065, 1118.558169523034, 12045.518179272818, 12053.15627906047, 12130.211536250115, 12149.514019439212, 12238.938805306005, 4151.7924103794685, 4310.676699247569, 4418.801153948016, 4512.390827238347, 4547.3179023146795, 9549.761255968657, 9559.283053770994, 9562.0, 9562.521873906277, 9584.28117891161, 5073.8731531712, 5075.923861142086, 5077.644564880497, 5078.923816142761, 5078.97460512695, 4018.517777866675, 4237.682173836913, 4327.82688692455, 4379.64962802975, 4792.808287668524, 3252.349530093991, 3651.068977410743, 3664.9633503665154, 3674.41209406499, 3701.851925922933, 1067.9092277156374, 1090.6400887707039, 1107.0921844087932, 1129.3506233915532, 1136.9260998035102, 779.563444471098, 873.2315562305258, 892.5403417240127, 914.1544071733753, 915.5788337364753, 5638.449426407688, 5648.15277708347, 5654.688992105392, 5711.685857277889, 5834.416558344132, 895.2927187521915, 898.4654130792168, 905.9864102038474, 938.15565990609, 965.6089283840056, 3623.4927110204594, 3737.906552336159, 3747.138158223624, 3784.4323351496946, 3819.2170605025904, 10448.425336606408, 10884.183686223407, 10892.61875834342, 11088.279261847954, 11200.551977920792, 11054.22620416563, 11059.557617695202, 11079.944600277107, 11084.445777711082, 11115.110791136689, 8087.1912808719835, 8352.373571982125, 8353.54055526952, 8355.874661879956, 8363.330933525303, 5381.381141168659, 5605.383407825218, 5641.351244606923, 5666.858328541819, 5803.593748437569, 9907.504624768588, 9918.454485003105, 9990.050945160197, 10100.646477373257, 10105.0, 9360.11078947499, 9921.851172232567, 10269.691909242734, 10396.532156052845, 10441.112505437122, 7257.455690823315, 7743.651535680795, 7846.686132554634, 7932.127465978622, 8135.7152499662225, 8220.958895205486, 8315.459495132796, 8331.416800823885, 8368.24685778283, 8441.620127094418, 7737.264959828807, 7775.300222979959, 7798.454108212371, 7801.29788319054, 7928.801779955316, 7761.223877612194, 7774.844503109793, 7778.377729781717, 7828.256315650232, 7870.72452464161, 6525.608463492205, 6686.231083425469, 6687.063811066552, 6699.698513566998, 6761.695723692544, 4767.404074490689, 4783.569478746929, 4803.183458811941, 4855.271709243641, 4944.134776414185, 4837.0, 4854.781534831011, 4895.902081958412, 4906.018796240837, 4913.926291105708], "value": 4503.995319363281, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.8333150093453, 332.1662626806715, 336.17133765268625, 338.54465743574883, 343.91402149462635, 5203.843884683461, 5316.20256961456, 5318.521333080022, 5323.627346085776, 5347.0, 4984.25236214568, 5057.659720174157, 5110.770015349318, 5174.0, 5271.209318602202, 4139.130782535656, 4144.9585504145025, 4155.272827255233, 4166.395872598471, 4169.498980367065, 4130.710850240484, 4143.0, 4157.168566286739, 4159.667226621863, 4181.184668989548, 3824.388097904336, 3907.785071821049, 4110.260153172428, 4112.979435102835, 4121.814518346667, 4114.670826333886, 4125.834966601333, 4127.587241275864, 4127.8968025799295, 4131.35963925592, 3661.377565813818, 3661.7253705972157, 3662.267546490686, 3668.743187976843, 3675.7426980111404, 5022.924656130161, 5058.924116138261, 5061.468545802674, 5131.384233891919, 5146.433892271847, 4647.581717645428, 4648.83729069481, 4667.6732628716, 4669.159551280768, 4684.250519916831, 5486.506214440679, 5487.0, 5494.203340515603, 5513.393526712059, 5529.806456774034, 3724.6089160638285, 3924.2543916655745, 3996.6403023728003, 4009.8997525061814, 4023.2154729827694, 5636.718164091779, 5653.689047102408, 5658.519025882805, 5664.716764161775, 5677.801276955328, 3959.940600890989, 3964.4053391990856, 3971.7021223407783, 3980.9848488635794, 3994.7802870842384, 3925.3130702127014, 4025.215083058834, 4085.836566537365, 4089.488813898263, 4100.569440208795, 4581.610563102107, 4587.954120458753, 4598.908021839545, 4618.930716039262, 4641.675082744243, 5463.371712253142, 5473.31583552056, 5490.313710786152, 5521.889562208814, 5572.498475137257, 1397.4969011155977, 1405.248192217159, 1424.1953296387715, 1424.2736204535554, 1426.664733787559, 883.5096521430668, 891.6700820696384, 894.0299774744345, 899.5187574647532, 905.7101727447285, 4764.332993380929, 4773.498782627844, 4782.3457980820285, 4799.6400269979895, 4799.832005879777, 4963.851084467472, 4998.750062496933, 5018.0, 5019.573336266385, 5020.7740651670765, 4755.144074066701, 4781.6891902026255, 4974.0797952378925, 5363.81226657073, 5442.2380866678695, 2986.596809430699, 3004.654464736583, 3004.9699503005127, 3007.323352245727, 3017.0087742090113, 5670.829875103754, 5874.089516125007, 5891.175235467038, 5905.734241959122, 5920.555958303058, 5726.99777538929, 5734.741936612862, 5746.712664366765, 5753.88492230153, 5763.279590051244, 5797.768089276382, 5827.970860145672, 5830.62100963436, 5838.328592211865, 5842.824715258549, 5399.974004939049, 5605.495505404533, 5610.382857885547, 5612.5509959202345, 5651.57613179005, 5529.778808847762, 5539.19681646151, 5540.584456165884, 5540.889182216252, 5542.722863856871, 5763.279590051244, 5774.971125144348, 5797.826065218051, 5851.069147181505, 5874.765009399747, 3842.193139440749, 4046.858359957387, 4054.1891621676373, 4103.425520427083, 4116.567760385236, 5333.799895023589, 5454.645448045864, 5527.50252477279, 5685.488306052475, 5714.285714285715, 5511.421300763523, 5511.944880551143, 5590.385057643574, 5597.720113994365, 5608.242887210175, 5499.340079190522, 5518.91721624184, 5536.446355364431, 5558.804856955774, 5611.775528978795, 5238.895222095613, 5493.368262649687, 5506.614536982373, 5513.145462453326, 5520.227168196376, 5537.169424586345, 5559.16612508127, 5563.77744890216, 5579.74891129892, 5645.153227015816, 5158.561522270649, 5390.2481693449, 5440.292761940922, 5452.945470545403, 5523.861903452445], "value": 4168.266457263174, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.5386479585463, 736.2784471218206, 776.1617453150596, 797.4856217739562, 822.4695071678764, 15362.539123826275, 16885.831141688563, 16908.1545922704, 16965.660686786257, 16986.150692465373, 15236.085834849917, 15384.692306153871, 15410.0, 15426.768598471032, 15455.149966751824, 636.6084857812435, 653.3107571512053, 771.6373304546854, 783.3576467296818, 809.76112046946, 802.3941923847487, 804.4770898915693, 804.5896592737687, 806.5846089264033, 806.6410447350931, 846.1622993236713, 847.6100993542977, 847.7795773099008, 849.3757088539918, 849.6856163219618, 675.5305062981206, 679.5514960126293, 697.435077587151, 698.2005603583868, 839.9244068033876, 601.1073555769678, 617.6448542088317, 618.6442795392667, 620.6586377492406, 622.3154530016946, 15016.774748378783, 15029.774553381709, 15035.699286014327, 15046.172460514666, 15086.849131508654, 14009.71980560383, 14163.716725665532, 14273.286335683279, 14300.642483937881, 14375.78436323456, 12538.623841284776, 12630.873691263061, 12686.0, 12886.226826390353, 13398.598042058753, 4355.7822108894425, 4380.452443444569, 4424.6239069678795, 4448.6885917985755, 4504.702601673171, 77643.61178194072, 78724.99999999943, 80174.0, 80587.0, 80814.59592702058, 10863.83704244437, 10925.945370273097, 11120.55517779296, 11199.328040317607, 12075.0, 21090.156393744386, 21204.469888252916, 21237.575248494944, 21266.36200913975, 21270.893645531673, 8122.959385203154, 8147.348212143014, 8210.917890821134, 8309.875351869727, 8359.247667709935, 4536.6824322297125, 4648.395708557866, 4693.78877950493, 4697.342372067913, 4776.522347765195, 3502.1594817243677, 3620.2759448109955, 3625.637436256406, 3636.2363903580545, 3640.2719456108885, 602.3795988790228, 607.60505671314, 616.0758861707438, 624.5815303746501, 624.950003999679, 649.5875119299279, 675.4495086504581, 683.1017212365723, 691.7647999680133, 697.9232284448808, 9212.447253164832, 9235.399699019543, 9318.580663870142, 9506.47714375716, 9686.951565242269, 575.7294071786254, 595.7706283080959, 618.9910445973035, 694.3230350408412, 700.7351730127169, 4810.95189048105, 4925.261210818406, 4953.925691114636, 4963.875903102379, 5048.0, 107625.0, 109126.45436772765, 109723.0, 112461.43769281101, 113632.43183784194, 90814.54592726994, 91989.54005230065, 92444.53777731069, 95538.0, 97634.0, 83460.0, 84243.57878210692, 86949.56525217333, 87051.56474217716, 89832.0, 9035.864462032943, 9213.170814626448, 9401.320623622234, 9462.952685236529, 9476.384035037701, 49750.502494974586, 49970.0, 50827.2375914369, 51257.46227613177, 51698.74150629223, 67237.66381168063, 73595.0, 74153.25846741463, 76257.23742762502, 77563.61218194099, 12092.697682558006, 12108.334041627795, 12141.210821296587, 12245.326507041842, 12705.745885082431, 13152.551773316532, 13388.19670819754, 13408.995167885021, 13738.725225495635, 13808.723825523231, 11334.0, 11724.58963936258, 12061.457234424297, 12141.817872732092, 12422.751544968869, 11507.482163302839, 12041.458134383798, 12069.75860482803, 12175.39123043862, 12186.086043546593, 12365.0, 12428.13003089775, 12555.937220314205, 13298.468061277828, 13301.135426197266, 9551.379160354554, 9606.27952903521, 10106.545205465625, 10134.290599657954, 10491.895081049397, 9549.665761698307, 10446.582136714751, 10543.103836174012, 10573.101286390744, 10574.15406767472], "value": 7369.537394703605, "unit": "score"}], "name": "dromaeo_css", "value": 7114.381450629268, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 111.0, 110.0, 110.0, 111.0, 112.0, 110.0, 110.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 120.0, 122.0, 158.0, 125.0, 136.0, 122.0, 122.0, 152.0, 126.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 161.0, 162.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 108.0, 77.0, 75.0, 76.0, 76.0, 121.0, 78.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 83.0, 83.0, 83.0, 104.0, 84.0, 84.0, 114.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 144.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 51.0, 51.0, 54.0, 53.0, 53.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 61.0, 61.0, 61.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 81.0, 79.0, 79.0, 80.0, 80.0, 81.0, 80.0, 86.0, 80.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 134.0, 139.0, 143.0, 131.0, 134.0, 132.0, 133.0, 170.0, 132.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 168.0, 169.0, 165.0, 168.0, 169.0, 167.0, 170.0, 167.0, 170.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 65.0, 67.0, 68.0, 66.0, 65.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1491.1000000000001, "alertThreshold": 2.0}]} 16:00:26 INFO - Return code: 0 16:00:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:26 INFO - # TBPL SUCCESS # 16:00:26 INFO - Running post-action listener: _resource_record_post_action 16:00:26 INFO - [mozharness: 2016-10-14 23:00:26.611448Z] Finished run-tests step (success) 16:00:26 INFO - Running post-run listener: _resource_record_post_run 16:00:26 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 393723904; Read time: 0; Write time: 250660 16:00:26 INFO - TinderboxPrint: CPU usage
13.1% 16:00:26 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:00:26 INFO - TinderboxPrint: I/O write bytes / time
393,723,904 / 250,660 16:00:26 INFO - TinderboxPrint: CPU idle
8,302.1 (86.9%) 16:00:26 INFO - TinderboxPrint: CPU user
1,199.1 (12.6%) 16:00:26 INFO - TinderboxPrint: Swap in / out
0 / 0 16:00:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102965248; Read time: 0; Write time: 53412 16:00:26 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 289816576; Read time: 0; Write time: 197228 16:00:27 INFO - Running post-run listener: _upload_blobber_files 16:00:27 INFO - Blob upload gear active. 16:00:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:28 INFO - (blobuploader) - INFO - Done attempting. 16:00:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:29 INFO - (blobuploader) - INFO - Done attempting. 16:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:32 INFO - (blobuploader) - INFO - Done attempting. 16:00:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:32 INFO - Return code: 0 16:00:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/433f56cee36a502970ffa7390e9652da3dc1aa1fccd98a9e9dc981261c90dd0101285d676b0de3c10a4833b36ab20310594fc344050390c3f11ea268776c582f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7995c6c7127926e39f4efdd04e49f9b60b5e9d108ad1356d60f46c2f3abad9191970928ba4c9193606f53ed358f5e9c8852f4cf6abaabb371a2f9479203df264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12a4cdbc58cca687bfa602f25e839c8c123374cfc91b0f31d530fdda0d26ebaf6fbe3e52b9e5517439a17ecbdf6e24a87d96f507f6beb0863c9ce4688d34b5d0"} 16:00:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:32 INFO - Contents: 16:00:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/433f56cee36a502970ffa7390e9652da3dc1aa1fccd98a9e9dc981261c90dd0101285d676b0de3c10a4833b36ab20310594fc344050390c3f11ea268776c582f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7995c6c7127926e39f4efdd04e49f9b60b5e9d108ad1356d60f46c2f3abad9191970928ba4c9193606f53ed358f5e9c8852f4cf6abaabb371a2f9479203df264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12a4cdbc58cca687bfa602f25e839c8c123374cfc91b0f31d530fdda0d26ebaf6fbe3e52b9e5517439a17ecbdf6e24a87d96f507f6beb0863c9ce4688d34b5d0"} 16:00:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:32 INFO - Copying logs to upload dir... 16:00:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.603320 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-14 16:00:32.584662) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 16:00:32.588166) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/433f56cee36a502970ffa7390e9652da3dc1aa1fccd98a9e9dc981261c90dd0101285d676b0de3c10a4833b36ab20310594fc344050390c3f11ea268776c582f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7995c6c7127926e39f4efdd04e49f9b60b5e9d108ad1356d60f46c2f3abad9191970928ba4c9193606f53ed358f5e9c8852f4cf6abaabb371a2f9479203df264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12a4cdbc58cca687bfa602f25e839c8c123374cfc91b0f31d530fdda0d26ebaf6fbe3e52b9e5517439a17ecbdf6e24a87d96f507f6beb0863c9ce4688d34b5d0"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476473403/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/433f56cee36a502970ffa7390e9652da3dc1aa1fccd98a9e9dc981261c90dd0101285d676b0de3c10a4833b36ab20310594fc344050390c3f11ea268776c582f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7995c6c7127926e39f4efdd04e49f9b60b5e9d108ad1356d60f46c2f3abad9191970928ba4c9193606f53ed358f5e9c8852f4cf6abaabb371a2f9479203df264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/12a4cdbc58cca687bfa602f25e839c8c123374cfc91b0f31d530fdda0d26ebaf6fbe3e52b9e5517439a17ecbdf6e24a87d96f507f6beb0863c9ce4688d34b5d0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 16:00:32.613543) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:00:32.613883) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1476484793.511275-1082925129 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:00:32.699779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 16:00:32.700194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 16:00:32.700485) ========= ========= Total master_lag: 0.18 =========