builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-048 starttime: 1472737945.95 results: success (0) buildid: 20160830093921 builduid: 86ba142624df48b3b49a4204efa8ff7c revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.952983) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.953372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.953654) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.127485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.130792) ========= 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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:27-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2016-09-01 06:52:27 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148337 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.382670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.382944) ========= 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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023243 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.486529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.486840) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:27,724 truncating revision to first 12 chars 2016-09-01 06:52:27,724 Setting DEBUG logging. 2016-09-01 06:52:27,724 attempt 1/10 2016-09-01 06:52:27,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c819e284a139?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:28,116 unpacking tar archive at: mozilla-aurora-c819e284a139/testing/mozharness/ program finished with exit code 0 elapsedTime=0.721253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.230358) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.230621) ========= script_repo_revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.230992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.231250) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.240065) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 9 secs) (at 2016-09-01 06:52:27.240332) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:28 INFO - MultiFileLogger online at 20160901 06:52:28 in /builds/slave/test 06:52:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:52:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:28 INFO - {'append_to_log': False, 06:52:28 INFO - 'base_work_dir': '/builds/slave/test', 06:52:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:28 INFO - 'branch': 'Mozilla-Aurora', 06:52:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:28 INFO - 'config_files': ('talos/linux_config.py',), 06:52:28 INFO - 'default_actions': ('clobber', 06:52:28 INFO - 'read-buildbot-config', 06:52:28 INFO - 'download-and-extract', 06:52:28 INFO - 'populate-webroot', 06:52:28 INFO - 'create-virtualenv', 06:52:28 INFO - 'install', 06:52:28 INFO - 'run-tests'), 06:52:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:28 INFO - 'download_minidump_stackwalk': True, 06:52:28 INFO - 'download_symbols': 'ondemand', 06:52:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:28 INFO - 'installer_path': 'installer.exe', 06:52:28 INFO - 'log_level': 'info', 06:52:28 INFO - 'log_name': 'talos', 06:52:28 INFO - 'log_to_console': True, 06:52:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:28 INFO - 'opt_config_files': (), 06:52:28 INFO - 'pip_index': False, 06:52:28 INFO - 'sps_profile': False, 06:52:28 INFO - 'sps_profile_interval': 0, 06:52:28 INFO - 'suite': 'dromaeojs-e10s', 06:52:28 INFO - 'system_bits': '32', 06:52:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:28 INFO - 'title': 'talos-linux64-ix-048', 06:52:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:28 INFO - 'use_talos_json': True, 06:52:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:28 INFO - 'work_dir': 'build'} 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.559156Z] Running clobber step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: clobber 06:52:28 INFO - rmtree: /builds/slave/test/build 06:52:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.029696Z] Finished clobber step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.029795Z] Running read-buildbot-config step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: read_buildbot_config 06:52:29 INFO - Using buildbot properties: 06:52:29 INFO - { 06:52:29 INFO - "project": "", 06:52:29 INFO - "product": "firefox", 06:52:29 INFO - "script_repo_revision": "production", 06:52:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 06:52:29 INFO - "repository": "", 06:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 06:52:29 INFO - "buildid": "20160830093921", 06:52:29 INFO - "pgo_build": "True", 06:52:29 INFO - "basedir": "/builds/slave/test", 06:52:29 INFO - "buildnumber": 524, 06:52:29 INFO - "slavename": "talos-linux64-ix-048", 06:52:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:29 INFO - "platform": "ubuntu64_hw", 06:52:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:29 INFO - "branch": "mozilla-aurora", 06:52:29 INFO - "repo_path": "releases/mozilla-aurora", 06:52:29 INFO - "revision": "c819e284a13937fc955c2efb6da7fdf9c5bb8b88", 06:52:29 INFO - "stage_platform": "linux64-pgo", 06:52:29 INFO - "builduid": "86ba142624df48b3b49a4204efa8ff7c", 06:52:29 INFO - "slavebuilddir": "test" 06:52:29 INFO - } 06:52:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.032296Z] Finished read-buildbot-config step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.032384Z] Running download-and-extract step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: download_and_extract 06:52:29 INFO - mkdir: /builds/slave/test/build/tests 06:52:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:29 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:52:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:29 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 06:52:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:59 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:53:59 INFO - Downloaded 1361 bytes. 06:53:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Using the following test package requirements: 06:53:59 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:59 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:59 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'jsshell-linux-x86_64.zip'], 06:53:59 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:59 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:59 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:59 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:59 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:59 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:59 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:54:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:29 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:30 INFO - Downloaded 21769265 bytes. 06:55:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:55:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:55:31 INFO - Return code: 0 06:55:31 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:55:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:31 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:31 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:56:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:01 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:57:03 INFO - Downloaded 21769265 bytes. 06:57:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:57:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:57:04 INFO - Return code: 0 06:57:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:04 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:57:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:04 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 06:57:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:34 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:58:35 INFO - Downloaded 14413723 bytes. 06:58:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:58:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:58:35 INFO - Return code: 0 06:58:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:35 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:58:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:35 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 06:59:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:05 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:00:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:00:07 INFO - Downloaded 63875997 bytes. 07:00:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:07 INFO - mkdir: /builds/slave/test/properties 07:00:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:07 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:07 INFO - Contents: 07:00:07 INFO - build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - Running post-action listener: set_extra_try_arguments 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.271628Z] Finished download-and-extract step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.271859Z] Running populate-webroot step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: populate_webroot 07:00:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:08 INFO - '--tppagecycles', 07:00:08 INFO - '7'], 07:00:08 INFO - 'tests': ['tsvgm']}}, 07:00:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s', 07:00:08 INFO - '--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}, 07:00:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}}, 07:00:08 INFO - 'talos.zip': {'path': '', 07:00:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.287105Z] Finished populate-webroot step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.287194Z] Running create-virtualenv step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: create_virtualenv 07:00:08 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:08 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 07:00:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:08 INFO - Using real prefix '/usr' 07:00:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:10 INFO - Installing pip.................done. 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:10 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 07:00:10 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 07:00:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:11 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 07:00:11 INFO - Cleaning up... 07:00:11 INFO - Return code: 0 07:00:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:11 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 07:00:11 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 07:00:11 INFO - Using env: {'DISPLAY': ':0', 07:00:11 INFO - 'HOME': '/home/cltbld', 07:00:11 INFO - 'LANG': 'en_US.UTF-8', 07:00:11 INFO - 'LANGUAGE': 'en_US:en', 07:00:11 INFO - 'LOGNAME': 'cltbld', 07:00:11 INFO - 'MAIL': '/var/mail/cltbld', 07:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:11 INFO - 'MOZ_NO_REMOTE': '1', 07:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:11 INFO - 'NO_EM_RESTART': '1', 07:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:11 INFO - 'PWD': '/builds/slave/test', 07:00:11 INFO - 'SHELL': '/bin/bash', 07:00:11 INFO - 'SHLVL': '1', 07:00:11 INFO - 'TERM': 'linux', 07:00:11 INFO - 'TMOUT': '86400', 07:00:11 INFO - 'USER': 'cltbld', 07:00:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:11 INFO - '_': '/tools/buildbot/bin/python'} 07:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:11 INFO - Downloading/unpacking psutil>=3.1.1 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Installing collected packages: psutil 07:00:13 INFO - Running setup.py install for psutil 07:00:13 INFO - building 'psutil._psutil_linux' extension 07:00:13 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 07:00:13 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 07:00:13 INFO - building 'psutil._psutil_posix' extension 07:00:13 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 07:00:13 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 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Successfully installed psutil 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:13 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 07:00:13 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 07:00:13 INFO - Using env: {'DISPLAY': ':0', 07:00:13 INFO - 'HOME': '/home/cltbld', 07:00:13 INFO - 'LANG': 'en_US.UTF-8', 07:00:13 INFO - 'LANGUAGE': 'en_US:en', 07:00:13 INFO - 'LOGNAME': 'cltbld', 07:00:13 INFO - 'MAIL': '/var/mail/cltbld', 07:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:13 INFO - 'MOZ_NO_REMOTE': '1', 07:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:13 INFO - 'NO_EM_RESTART': '1', 07:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:13 INFO - 'PWD': '/builds/slave/test', 07:00:13 INFO - 'SHELL': '/bin/bash', 07:00:13 INFO - 'SHLVL': '1', 07:00:13 INFO - 'TERM': 'linux', 07:00:13 INFO - 'TMOUT': '86400', 07:00:13 INFO - 'USER': 'cltbld', 07:00:13 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:13 INFO - '_': '/tools/buildbot/bin/python'} 07:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:14 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 07:00:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:16 INFO - Installing collected packages: mozsystemmonitor 07:00:16 INFO - Running setup.py install for mozsystemmonitor 07:00:16 INFO - Successfully installed mozsystemmonitor 07:00:16 INFO - Cleaning up... 07:00:16 INFO - Return code: 0 07:00:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:16 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 07:00:16 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 07:00:16 INFO - Using env: {'DISPLAY': ':0', 07:00:16 INFO - 'HOME': '/home/cltbld', 07:00:16 INFO - 'LANG': 'en_US.UTF-8', 07:00:16 INFO - 'LANGUAGE': 'en_US:en', 07:00:16 INFO - 'LOGNAME': 'cltbld', 07:00:16 INFO - 'MAIL': '/var/mail/cltbld', 07:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:16 INFO - 'MOZ_NO_REMOTE': '1', 07:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:16 INFO - 'NO_EM_RESTART': '1', 07:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:16 INFO - 'PWD': '/builds/slave/test', 07:00:16 INFO - 'SHELL': '/bin/bash', 07:00:16 INFO - 'SHLVL': '1', 07:00:16 INFO - 'TERM': 'linux', 07:00:16 INFO - 'TMOUT': '86400', 07:00:16 INFO - 'USER': 'cltbld', 07:00:16 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:16 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Installing collected packages: jsonschema, functools32 07:00:18 INFO - Running setup.py install for functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Successfully installed jsonschema functools32 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:18 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 07:00:18 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 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:18 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 07:00:18 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 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 INFO - Downloading docopt-0.6.1.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:21 INFO - Running setup.py install for blobuploader 07:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:21 INFO - Running setup.py install for requests 07:00:22 INFO - Running setup.py install for docopt 07:00:22 INFO - Successfully installed blobuploader requests docopt 07:00:22 INFO - Cleaning up... 07:00:22 INFO - Return code: 0 07:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:22 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 07:00:22 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 07:00:22 INFO - Using env: {'DISPLAY': ':0', 07:00:22 INFO - 'HOME': '/home/cltbld', 07:00:22 INFO - 'LANG': 'en_US.UTF-8', 07:00:22 INFO - 'LANGUAGE': 'en_US:en', 07:00:22 INFO - 'LOGNAME': 'cltbld', 07:00:22 INFO - 'MAIL': '/var/mail/cltbld', 07:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:22 INFO - 'MOZ_NO_REMOTE': '1', 07:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:22 INFO - 'NO_EM_RESTART': '1', 07:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:22 INFO - 'PWD': '/builds/slave/test', 07:00:22 INFO - 'SHELL': '/bin/bash', 07:00:22 INFO - 'SHLVL': '1', 07:00:22 INFO - 'TERM': 'linux', 07:00:22 INFO - 'TMOUT': '86400', 07:00:22 INFO - 'USER': 'cltbld', 07:00:22 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:22 INFO - '_': '/tools/buildbot/bin/python'} 07:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Running setup.py (path:/tmp/pip-M0HPBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Running setup.py (path:/tmp/pip-g10d3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Running setup.py (path:/tmp/pip-8KK25H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Running setup.py (path:/tmp/pip-v_IZAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Running setup.py (path:/tmp/pip-Gv_H6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 INFO - Running setup.py (path:/tmp/pip-vppnDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 INFO - Running setup.py (path:/tmp/pip-mq2aY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Running setup.py (path:/tmp/pip-1jWF8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Running setup.py (path:/tmp/pip-3fskRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Running setup.py (path:/tmp/pip-cOWqIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Running setup.py (path:/tmp/pip-Mg69Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Running setup.py (path:/tmp/pip-eMZEC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Running setup.py (path:/tmp/pip-fklWAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Running setup.py (path:/tmp/pip-n939QA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Running setup.py (path:/tmp/pip-XSw3w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Running setup.py (path:/tmp/pip-X0morF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Running setup.py (path:/tmp/pip-Bz2jsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:23 INFO - Running setup.py install for manifestparser 07:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozcrash 07:00:24 INFO - Running setup.py install for mozdebug 07:00:24 INFO - Running setup.py install for mozdevice 07:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozfile 07:00:24 INFO - Running setup.py install for mozhttpd 07:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozinfo 07:00:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozInstall 07:00:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozleak 07:00:24 INFO - Running setup.py install for mozlog 07:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for moznetwork 07:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozprocess 07:00:25 INFO - Running setup.py install for mozprofile 07:00:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozrunner 07:00:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozscreenshot 07:00:25 INFO - Running setup.py install for moztest 07:00:25 INFO - Running setup.py install for mozversion 07:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:25 INFO - Cleaning up... 07:00:25 INFO - Return code: 0 07:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:25 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 07:00:25 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 07:00:25 INFO - Using env: {'DISPLAY': ':0', 07:00:25 INFO - 'HOME': '/home/cltbld', 07:00:25 INFO - 'LANG': 'en_US.UTF-8', 07:00:25 INFO - 'LANGUAGE': 'en_US:en', 07:00:25 INFO - 'LOGNAME': 'cltbld', 07:00:25 INFO - 'MAIL': '/var/mail/cltbld', 07:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:25 INFO - 'MOZ_NO_REMOTE': '1', 07:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:25 INFO - 'NO_EM_RESTART': '1', 07:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:25 INFO - 'PWD': '/builds/slave/test', 07:00:25 INFO - 'SHELL': '/bin/bash', 07:00:25 INFO - 'SHLVL': '1', 07:00:25 INFO - 'TERM': 'linux', 07:00:25 INFO - 'TMOUT': '86400', 07:00:25 INFO - 'USER': 'cltbld', 07:00:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:25 INFO - '_': '/tools/buildbot/bin/python'} 07:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Running setup.py (path:/tmp/pip-EX587q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Running setup.py (path:/tmp/pip-UpSgZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:26 INFO - Running setup.py (path:/tmp/pip-S7AeFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:26 INFO - Running setup.py (path:/tmp/pip-8BiI4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:26 INFO - Running setup.py (path:/tmp/pip-Bef2Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 INFO - Running setup.py (path:/tmp/pip-EZOCqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 INFO - Running setup.py (path:/tmp/pip-JDDs95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 INFO - Running setup.py (path:/tmp/pip-HyWaMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:26 INFO - Running setup.py (path:/tmp/pip-MGu97w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Running setup.py (path:/tmp/pip-j6eVkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 INFO - Running setup.py (path:/tmp/pip-rQ4Dta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 INFO - Running setup.py (path:/tmp/pip-lJo7mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Running setup.py (path:/tmp/pip-MKvpgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Running setup.py (path:/tmp/pip-fzfDde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Running setup.py (path:/tmp/pip-uH2GgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:26 INFO - Running setup.py (path:/tmp/pip-G9ItX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:27 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)) 07:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:27 INFO - Running setup.py (path:/tmp/pip-Ve8Vx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:29 INFO - Downloading blessings-1.6.tar.gz 07:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:29 INFO - Installing collected packages: blessings 07:00:29 INFO - Running setup.py install for blessings 07:00:29 INFO - Successfully installed blessings 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Current package versions: 07:00:29 INFO - argparse == 1.2.1 07:00:29 INFO - blessings == 1.6 07:00:29 INFO - blobuploader == 1.2.4 07:00:29 INFO - docopt == 0.6.1 07:00:29 INFO - functools32 == 3.2.3-2 07:00:29 INFO - jsonschema == 2.5.1 07:00:29 INFO - manifestparser == 1.1 07:00:29 INFO - mozInstall == 1.12 07:00:29 INFO - mozcrash == 0.17 07:00:29 INFO - mozdebug == 0.1 07:00:29 INFO - mozdevice == 0.48 07:00:29 INFO - mozfile == 1.2 07:00:29 INFO - mozhttpd == 0.7 07:00:29 INFO - mozinfo == 0.9 07:00:29 INFO - mozleak == 0.1 07:00:29 INFO - mozlog == 3.3 07:00:29 INFO - moznetwork == 0.27 07:00:29 INFO - mozprocess == 0.23 07:00:29 INFO - mozprofile == 0.28 07:00:29 INFO - mozrunner == 6.12 07:00:29 INFO - mozscreenshot == 0.1 07:00:29 INFO - mozsystemmonitor == 0.3 07:00:29 INFO - moztest == 0.7 07:00:29 INFO - mozversion == 1.4 07:00:29 INFO - psutil == 3.1.1 07:00:29 INFO - requests == 1.2.3 07:00:29 INFO - wsgiref == 0.1.2 07:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:29 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 07:00:29 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 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x1caf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ede400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, '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': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', '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 07:00:29 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 07:00:29 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 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Running post-action listener: _resource_record_post_action 07:00:29 INFO - Running post-action listener: _start_resource_monitoring 07:00:29 INFO - Starting resource monitoring. 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.988062Z] Finished create-virtualenv step (success) 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.988538Z] Running install step. 07:00:29 INFO - Running pre-action listener: _resource_record_pre_action 07:00:29 INFO - Running main action method: install 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:30 INFO - Reading from file tmpfile_stdout 07:00:30 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:30 INFO - Reading from file tmpfile_stdout 07:00:30 INFO - Output received: 07:00:30 INFO - Usage: mozinstall [options] installer 07:00:30 INFO - Options: 07:00:30 INFO - -h, --help show this help message and exit 07:00:30 INFO - -d DEST, --destination=DEST 07:00:30 INFO - Directory to install application into. [default: 07:00:30 INFO - "/builds/slave/test"] 07:00:30 INFO - --app=APP Application being installed. [default: firefox] 07:00:30 INFO - mkdir: /builds/slave/test/build/application 07:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:00:46 INFO - Reading from file tmpfile_stdout 07:00:46 INFO - Output received: 07:00:46 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:46 INFO - Running post-action listener: _resource_record_post_action 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.392452Z] Finished install step (success) 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.392728Z] Running run-tests step. 07:00:46 INFO - Running pre-action listener: _resource_record_pre_action 07:00:46 INFO - Running main action method: run_tests 07:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:46 INFO - Python 2.7.3 07:00:46 INFO - Return code: 0 07:00:46 INFO - grabbing minidump binary from tooltool 07:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00: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 0x1f6d570>, '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 0x1f6e470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00: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 07:00: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 07:00: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 07:00:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:46 INFO - Return code: 0 07:00:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:00:46 INFO - Using env: {'DISPLAY': ':0', 07:00:46 INFO - 'HOME': '/home/cltbld', 07:00:46 INFO - 'LANG': 'en_US.UTF-8', 07:00:46 INFO - 'LANGUAGE': 'en_US:en', 07:00:46 INFO - 'LOGNAME': 'cltbld', 07:00:46 INFO - 'MAIL': '/var/mail/cltbld', 07:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:46 INFO - 'MOZ_NO_REMOTE': '1', 07:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:46 INFO - 'NO_EM_RESTART': '1', 07:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:46 INFO - 'PWD': '/builds/slave/test', 07:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:46 INFO - 'SHELL': '/bin/bash', 07:00:46 INFO - 'SHLVL': '1', 07:00:46 INFO - 'TERM': 'linux', 07:00:46 INFO - 'TMOUT': '86400', 07:00:46 INFO - 'USER': 'cltbld', 07:00:46 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760', 07:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:46 INFO - '_': '/tools/buildbot/bin/python'} 07:00:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:00:46 INFO - mozversion application_buildid: 20160830093921 07:00:46 INFO - mozversion application_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:46 INFO - mozversion application_name: Firefox 07:00:46 INFO - mozversion application_remotingname: firefox-dev 07:00:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion application_vendor: Mozilla 07:00:46 INFO - mozversion application_version: 50.0a2 07:00:46 INFO - mozversion platform_buildid: 20160830093921 07:00:46 INFO - mozversion platform_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion platform_version: 50.0a2 07:00:46 INFO - using testdate: 1472738446 07:00:46 INFO - actual date: 1472738446 07:00:46 INFO - starting webserver on 'localhost:44576' 07:00:46 INFO - SUITE-START | Running 2 tests 07:00:46 INFO - TEST-START | dromaeo_css 07:00:46 INFO - operating with platform_type : linux_ 07:00:46 INFO - Initialising browser for dromaeo_css test... 07:00:46 INFO - TEST-INFO | started process 12018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpClqhxY/profile http://localhost:44576/getInfo.html) 07:00:51 INFO - PROCESS | 12018 | __metrics Screen width/height:1600/1200 07:00:51 INFO - PROCESS | 12018 | colorDepth:24 07:00:51 INFO - PROCESS | 12018 | Browser inner width/height: 1024/697 07:00:51 INFO - PROCESS | 12018 | __metrics 07:00:52 ERROR - PROCESS | 12018 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:00:53 INFO - TEST-INFO | 12018: exit 0 07:00:53 INFO - Browser initialized. 07:00:53 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:53 INFO - TEST-INFO | started process 12149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpClqhxY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:04 INFO - PROCESS | 12149 | RSS: Main: 152207360 07:01:04 INFO - PROCESS | 12149 | 07:01:54 INFO - PROCESS | 12149 | 1472738514516 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] 07:04:03 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44576/tests/dromaeo/cssquery-ext.html) 07:04:04 INFO - PROCESS | 12149 | RSS: Main: 158429184 07:04:04 INFO - PROCESS | 12149 | 07:07:06 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-ext.html (next: http://localhost:44576/tests/dromaeo/cssquery-jquery.html) 07:07:06 INFO - PROCESS | 12149 | RSS: Main: 153976832 07:07:06 INFO - PROCESS | 12149 | 07:10:08 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44576/tests/dromaeo/cssquery-mootools.html) 07:10:09 INFO - PROCESS | 12149 | RSS: Main: 153755648 07:10:09 INFO - PROCESS | 12149 | 07:13:11 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44576/tests/dromaeo/cssquery-prototype.html) 07:13:11 INFO - PROCESS | 12149 | RSS: Main: 152616960 07:13:11 INFO - PROCESS | 12149 | 07:16:14 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44576/tests/dromaeo/cssquery-yui.html) 07:16:14 INFO - PROCESS | 12149 | RSS: Main: 155320320 07:16:14 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | Cycle 1(1): loaded http://localhost:44576/tests/dromaeo/cssquery-yui.html (next: http://localhost:44576/tests/dromaeo/cssquery-dojo.html) 07:19:17 INFO - PROCESS | 12149 | RSS: Main: 156303360 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | __start_tp_report 07:19:17 INFO - PROCESS | 12149 | _x_x_mozilla_page_load 07:19:17 INFO - PROCESS | 12149 | _x_x_mozilla_page_load_details 07:19:17 INFO - PROCESS | 12149 | |i|pagename|runs| 07:19:17 INFO - PROCESS | 12149 | |0;dojo.html;3321.2859235264414;3362.680545348191;3369.39350916835;3373.139849338418;3394.0666316762904;5813.098969659704;5861.296644402678;5896.616719913213;5927.2590926134235;5939.109133629963;5816.767329306833;5856.502197313233;5937.198478205441;5961.61249518781;5975.073863551156;5340.279062326585;5358.919616205741;5396.244525766396;5432.076546987022;5467.289252397183;5338;5344;5370.892582148354;5394.32570928634;5403.729813509309;5338.27933229015;5350.27771250882;5364.195370694408;5368.570514358861;5386.030514507387;5430.864228394283;5433.1035379162395;5439.292891924025;5447.591430642698;5480.369757477862;5046.419661738911;5189.532942035235;5229.973850130763;5323.121684921984;5334.893302133974;5790;5849.970750146222;5863.560232982501;5876.382979787104;5913.852153696192;6259.593126446812;6291.6854157291955;6331.4934805215935;6358;6368.777092801776;6134.681512609229;6273.7176827042895;6286.87426251477;6353.142325786006;6376.107344971708;5964.850878728023;5972.163897054415;5975.701214939279;5980.700964951735;6004.78983235589;6423.66729565695;6488.28628850826;6492.837679058061;6557.704903279364;6560.770373036921;6061.36355682647;6125.815438131368;6158.938410615926;6296.968515157395;6305.464035556937;6319.58922670025;6359.300476947532;6378.075179099004;6380.0748891410485;6405.071264666597;6317.936820631827;6319.368063193644;6325.65208913505;6331.905021424683;6333.714982825784;6081.483073938676;6168.568200226031;6172.753089876444;6344.587601805868;6355.046742988588;4733.905321893543;4734.573888350065;4738.976305118521;4758.405199350082;4775.06886157196;4250.55369186231;4254.893627659333;4267.679924005712;4272.29507131326;4292.570742925683;5939.198208241918;5974.761009559656;5985.102234664835;5995.970020149872;6071.9696401518595;6346.587471814318;6358.205224346957;6381.074744162071;6385.840353991094;6386.616802991835;6085.3306136325855;6094.085887116879;6095.299040610386;6096.939030609726;6097.146399504073;5357.598180136505;5406.243125962368;5433.4566543345345;5445.759339327629;5490.094134467848;6583.177102862142;6637.369449902251;6640.136782218281;6642.63465509392;6648.335166483313;6368.649724265025;6380.330065343258;6397.168368112116;6422.935770642233;6437.195350581177;6396.71214795326;6484.1894763154605;6501.414872661483;6572.14562106923;6640.800775976729;6281.214848143982;6287.182666253358;6306.810795676137;6308.810735677937;6314.242290925118;6378.298387177314;6405.423511883952;6408.903866441914;6409.198850143732;6424.229092508742;6118.60229085108;6123.265208174869;6140.078988151813;6266.843328916631;6287.559870808999;6110.072772100023;6141.201643786279;6175.073738939195;6177.783777567944;6181.134641150153;6130.448259656652;6186.876262474635;6194.876102478015;6197.25632924052;6199.442050215503;6061.302950160788;6119.807225076679;6129.633877546871;6248.593841400295;6256.6246025236815;6093.9085913712215;6105.572609917263;6128.448439640454;6130.295016073209;6137.3862613738265 07:19:17 INFO - PROCESS | 12149 | |1;ext.html;589.2192844481064;624.9531285153613;653.1378580274037;666.9899951500728;709.1171491493092;42082.57917420829;47176.292355614605;47282.76358618207;47442;47484;47023.76488117563;47602.285965710544;47607.28589071158;47695.761521192435;47764;9026.909730902706;9098.31762617803;9163.816723665523;9171.633134674605;9182.816343673123;9135.36052476327;9177.908220917772;9201.217896478807;9222.631094756201;9230.492322922237;9239.076092390775;9258.120478554527;9268.953655231748;9289.117533834275;9297.163255307021;9183.862242066374;9193.494357810318;9198.356115071914;9204.631814727403;9245.445273283625;9197.356185067049;9235.953820230856;9252.398594091363;9261.722148335493;9285.628574856997;44918.32622510665;45069.323960140624;45118.77440612809;45236.77381613104;45256.77371613121;3653.214558869829;3724.1992971511254;3829.272438236726;3875.0506125999027;4042.7170098093147;34442.31115377678;34679.826600866836;34908;37898.81050594757;38102.80948595267;4665.2768820832825;4733.928991065137;4761.166795810719;4786.353842231289;4789;381620;383321;383517;383841;387010;68269.31730682729;68997.31002690009;69171;69212;69509;39891.40162897559;40227.19545609071;40640.39039414411;43580.56419435828;72673;19449.4165175045;19823.702644460343;19827.206911723657;19836.60326793456;19850.60298794045;14809.259537023107;14887.70224595502;14965;14976.17631030282;14976.400943962119;21146.471338216666;21592.35222943314;21618.243361482273;21618.243361482586;21637.35087947364;7577.772666820004;7622.923770762332;7718.498297610749;7722.150563438017;7875.527468351917;1002.4035698759227;1057.4448414582405;1128.836318733779;1154.163231657042;1191.094767976327;34733.47899781505;34793.652063479545;34881;34889.65110348864;35442;1128.2948157401624;1146.7935771561033;1176.2707121584672;1254.2349167008117;1282.8845403913692;17650.205740741698;17740.20169092394;18150.727739083923;18429.723554146698;18497;409774;649239;657004;664255;664575;64580.35419645837;65041.67479162574;65096.674516627114;65232.34767652263;65468.67265663641;1432.0476882872981;1458.4385011770332;1477.409036385433;1477.541961991786;1509.6150481627114;35144.64855351414;35831.10422239361;36015.81992090023;36016.4597531032;36035.63964360428;58432.4156758427;58814.705926470095;58823;59236;59336.109958351524;538540;545995;548949;549434;550623;31789.68210317867;32414.51378229281;32562.51156232611;32774.67225327716;32789.18027049248;52605;53456.465435346705;54257;54589.45410546003;54733.72633136809;44918.3262251073;45601.31598025966;45847.77076114598;46142.538574615166;47376.52623473721;44550.55449445464;44736.32895506505;45426.31860522161;46488.767556162005;46686;44771.10457790891;45646;45740.31389529226;46090.539094608626;48140.27789583229;38788.224235514564;39145.41281880831;39463.210735785695;40691.389629156176;40854.38718419167;30246.904389686915;31013.534796978514;31546.526802097527;31738.682613173572;31865.6813431872 07:19:17 INFO - PROCESS | 12149 | |2;jquery.html;413.842739758892;417.7121282999967;433.198582622149;434.3289617540899;444.9577290157436;19672.11475483603;21267.25564605237;21469.892650536745;22451.887740561273;22589.096436142576;19478.513037174078;19658.01709914502;19776.802231977676;19849.404517864452;19902.60194796107;5949.286085669725;6148;6159.045347971071;6198.287196972361;6217.937820621803;5743.483086522212;6028.125921741344;6056.727447264883;6080.331163572026;6122.142899994004;6151.384861513858;6155.784547540837;6170.228721409824;6170.938290617104;6171.783987560436;5972.611780234292;6107.603005804631;6124.050772130327;6141.4472697456995;6144.539159563028;5909.143174239754;5924.348321684612;5983.850403739898;5986.161937328778;6016.759329626853;14791.778123328158;15017.624559385993;15037.548873533702;15042.623934401618;23570.764292357027;2355.587772139869;2474.0969546115716;2508.7240403555593;3035.2867076237067;3056.0526236866644;11099.38953357565;13581.456741730319;14382.92808535961;15562;16087.758683619755;1934.642091213116;1956.6967120096408;1987.2647120565339;2020.1919232307196;2083.197968782015;82194.58902705507;82208.58895705483;84556.99999999939;85995.57002214949;87003.56498217532;11779.941100294443;11852.466639001266;11937.403129843648;12061.095417843697;12087.576934807239;16877.071761053016;16890.493285201464;17008.914955425393;17021.063841488838;17029.91485042567;8575.485470871768;9116.954415227881;9216.262698984065;9305.767355816159;9411.529423528933;8746.475211487332;8761.765749733904;8890.288776897833;9017.143371379709;9066.54667266634;4987.825426110068;5072.239164125337;5100.158473851773;5201.141811601044;5203.765830537559;2107.167668770827;2109.7215007791547;2123.2144106680626;2158.276977212651;2180.0843645668856;944.9244060475146;1011.3426272922659;1027.8047171037479;1038.8493668418037;1057.6668349469892;20150.496237593885;20450;20705.23779530686;20789.480262993242;21039.579208415747;1033.6227277043824;1038.3250886923408;1073.2058276875057;1077.256692881909;1089.5696200000955;10292;11014.944925275322;11101.389423581619;11365.545378184945;11634.592789252336;525770;542514;544016;545433;559369;95470.52264738771;96689;98567.50716246373;99149;101507.49246253721;1019.7756493571402;1073.7154654016847;1116.8045592021365;1138.4990604134155;1140.5836869542557;17944.371946982315;17947.282108715506;18375.54061148482;18635.347762828234;19193.52016199606;45602;46713.53286467092;46876;46945.53054469548;47751.28373074337;61855.07217391833;62601;62626.686866565375;63466.36533634605;63742.36257637364;8951.005806461171;9023.368364214442;9173.724788256362;9436.056394360783;9458.24334053288;13299.20204787716;13476.93261533686;13596.388162532512;14109.506167284097;14418.42326306936;11911.463984120563;12085.516579336729;12115.75768484643;12126.575569855013;12136.33250171249;12307.507699691912;12348.259104453762;12486.438110284877;12550.87449125497;12854.614361569167;11979;12058.638240852437;12091.153619246568;12120.575779847662;12158.209716368408;9193.310501712533;9242.907570924204;9280.164785169367;9294.442333460014;9638.710838674851;9046.738315421144;11873.940630296793;11885.28688278706;12160.939195304321;12195.756084878076 07:19:17 INFO - PROCESS | 12149 | |3;mootools.html;1382.7856682214253;1474.3365485531513;1519.0961377980097;1684.3094331324153;1716.081896185541;8301.792455188623;9087.227585655219;9191.862122068174;9881.16010139138;9888.307818452711;9243.306751993594;9518.14336709696;9552.522373881313;9615.711528654134;9636.662716804913;2524.129175434472;2571.241483762285;2573.086554273234;2576.4331846993723;2579.2778022153734;2491.289982355029;2530.987345063272;2580.870956452179;2581.5224183526107;2589.5727205011244;2499.9250022499264;2514.88683009265;2516.119358224616;2529.165375426114;2537.251510804308;2459.3974476253343;2488.5645012122895;2488.8880000399936;2507.1976967370447;2523.558377283968;2453.362125847275;2456.250843492734;2461.052494789501;2465.827392082555;2475.1708177760293;9620.615175392975;9793.755156121082;9798.608055677765;9825.557849896772;9858.950705246429;5551.805686800983;5686.289213848269;5687.203791469197;5697.914531282034;5706.315242170925;3676.926461470782;3795.734298599099;3854.0557563396865;3887.261420330128;3899.7270191086636;1241.5778635263964;1260.8802163794426;1278.5972418688084;1294.922304661714;1324.040070948564;13450.93274533631;13585.86414135856;13598.11612245201;13614.251216183105;13658.317084145752;4945.208766597327;4975.427825800007;5101.948980510221;5147.176451767699;5199.974000130051;11371.658850234506;11394.772104557862;11397.487113079931;11417.20079594437;11444.198906076661;5768.394318596571;5775.768969241268;5799.3040835100055;5810.12848072784;5813.709314534256;4743.881402964885;5145.151050076695;5231.372235331784;5282.550983166474;5313.920291195635;4291.785410729513;4350.499692535335;4397.560243975577;4400.44994375703;4402.25161722508;1373.9450421983208;1379.7723375643106;1405;1414.370605080729;1423.3168079321983;904.6426661468814;987.9429010958349;1055.8574592430077;1060.3054998975683;1061.0450594465044;6297.653629050446;6333.524985626096;6351.904721429182;6387.712552935129;6575.342465753482;499.0493110624267;505.19673718787305;506.70104638262865;508.40516595583256;511.3173912826344;4120.670346372343;4220.746755194698;4240.851570195028;4255.276602977501;4274.5939135782055;11673.883261167279;11830;11962.342071185994;12046.638600841989;12164.878351216552;11388.487518061707;11863.16957812962;11890.28658280506;11913.821292680617;11938.104642151873;8879.422837515542;9010.59452324647;9082.636694532277;9084.682036128705;9109;6515.381038801306;6563;6725.461963042847;6834.658267086726;6900.482463815334;10724.463776810971;10972.341659500455;11061.11511079128;11152.721181970515;11250.099992000783;11143.164262680493;11328.886711132784;11340.546378144782;11396.259243149168;11491.310521368745;8064.274215320649;8194.385421093322;8252.83494330122;8452.577371131541;8734.170253825876;9439.952800235955;9490.525473726148;9559.665411710557;9572.856407153758;9574.186194173573;8123.593820309079;8396;8431.241188293083;8470.66117355299;8512.744617661232;8135.227153420416;8219.917800821915;8274.793130171553;8317.209865062809;8337.333013359039;7525.059367579052;7659.272369124924;7725.072991240862;7769.689212431439;7777.988861447976;5438.972805135949;5561.5272701820795;5598.244237027949;5614.606977511535;5753.683547404766;5516.227728117987;5527.2538207341495;5542.946840100368;5592.860178495407;5667.036603777286 07:19:17 INFO - PROCESS | 12149 | |4;prototype.html;344.23064450952126;350.5040367879451;355.1051350596497;367.48919002586393;367.61951380321375;5589;5654.123610840326;5781.595288329813;5872.412758724131;5916.319623243328;5650.74571644276;5699.943000569993;5724.856878578038;5726.484616384525;5734.455226753452;4522.706024108439;4524.185646583614;4538.410006699141;4547.681662283641;4550.226461501553;4498.955010449887;4510.526394728556;4544.7045942013665;4549.340345649879;4613.838515651953;4453.51011388747;4483.618892394151;4495.056038231975;4504.4594648642205;4514.503404625489;4523.638108951292;4533.639908027611;4552.203364411248;4561.771911404417;4567.543245675439;3885.320068987944;3941.2905676978135;3993.7803420811842;4021.336479480882;4030.415589739471;5569.331680198402;5572.972135139298;5602.38373778888;5607.915881261784;5669.546436285129;5030.471800460972;5156.252343410222;5161.793528258865;5170.715610641413;5219.347581552306;5982.341942386334;6036.879262414727;6049.48579370754;6055.455009049163;6086.360932102154;4301.870943871689;4314.309710446345;4338.631216346615;4374.759388233646;4381.802818873127;6104.96947515264;6347.968260158716;6376.234851817765;6393.93606063938;6417.197850268716;4442.844500442469;4481.395011673448;4489.281714925596;4497.730136191773;4504.234280172379;4562.771861406983;4563.995920897397;4575.9084818303445;4577.977110114494;4579.931301030487;5017.197248440232;5184.196449550326;5190.6107041972;5202.4277329493725;5219.982103489859;5901.675407852609;5984.521238300926;6024.433647251746;6041.2750469943885;6115.266168059861;1533.4709525214007;1556.5408204579676;1558.6103474131467;1560.9151639610361;1565.2017471089825;973.255459573427;988.8269552828226;1000.6497725795913;1005.7133716890651;1010.777628921636;5176.404713457925;5205.7397130143345;5212.687238765686;5223.973880130575;5238.371395432572;5535.750891209906;5542.392706115262;5553.916691249554;5577.247071645357;5588.6646801192055;5084.745762711849;5195.714235716996;5296.25852380667;5318.393986946368;5318.414974352818;2957.038962337249;2973.721299841046;2975.8691697155377;2984.7612191024664;3008.4807172378246;5699.772009119672;5761.452661997104;5773.682447465346;5799.2750906136735;5799.681017543991;5216.869578260574;5568.524341049625;5576.275084239023;5659.377468478464;5687.914681279784;5732.426757324234;5779.88440231193;5800.53595712342;5810.970945145163;5815.505682017076;5382.703951282717;5384.919226211531;5576.442355764553;5591.440855914376;5602.59935016246;5414.810481633124;5426.375966763872;5508.697021663847;5517.917231241454;5539.17207321591;5628.521575665951;5667.518260947701;5678.77284908599;5702.692438385643;5787.276590426197;4053.5946405359227;4063.6342729154517;4376.715513491613;4415.867523974286;4423.16438677945;6054.455099041107;6198.473129783839;6249.875022495994;6268;6295.622262664255;6064.878702426015;6074.817755467343;6078.787242446493;6101.60339577926;6103.572749907641;6088.573799833969;6101.786437474667;6126.172966649446;6130.570860039755;6150.630962142286;5814.970925145347;6033.969830150997;6077.179580756718;6085.391460853879;6090.969545152246;6163.7842675506145;6169.691515424301;6186.81439556814;6190.907136392868;6192.783252586138;5779.930712818142;5973.610555777724;5988.431099045583;6019.969900150471;6040.369100342795 07:19:17 INFO - PROCESS | 12149 | |5;yui.html;825.2201669422394;833.7790485521334;888.3603805260212;891.3715830339611;907.02947845805;16378.099204543745;16810;17195.828041719593;17416.47750567482;17448.214830332632;14934.701305973875;14969.251537423139;15050.774238386433;15068.719158871509;15331.386744530233;721.4913485992363;868.9131086891324;887.1128871128872;889.1242126505387;891.5809569502323;880.9911900880974;884.5975081338008;887.6493784954939;892.5983307511614;896.2964073202537;939.9906000940006;942.9528523573828;943.7640589852537;946.0397696338222;947.0671388682142;927.9860802087974;930.6603089872216;934.4346670264495;936.7143021378477;938.4041133879967;686.6086330791451;689.4759982413352;691.353584398589;692.4425837201051;695.5166159519136;15851.207439627973;15889.284982175714;15920.442784502602;15930.522084337488;15949.20253987296;15171.848281517154;15193.468228612057;15509.302081406364;15539.922300388538;15607.367823984296;13233.536826211037;13237.669058273525;13312.156945006249;13416.5304214353;13534.864651353459;4150.813213405404;4176.331786914079;4189.392538081992;4257.233697934371;4263.8081286342185;89266.55366723124;89624.55187724085;90867.54566227192;91052;91106.5444672779;12448.688782780322;12576.182548134342;12692.873071269169;12752.171108877894;12803.487860485477;23667.408314791923;23792.762072379403;23800.04799808023;24109.15617953363;24179.39551511191;8844.071372505923;8890.110988901188;9055.320850936207;9121.407108537924;9141.360104792733;5078.415982162099;5136.1525348317855;5208.661437006658;5229.843104706865;5337.839864804062;4051.210014047291;4132.73137246078;4165.66674666032;4178.979105104455;4181.916361672749;692.9064576282238;695.4818660098259;700.7127077898037;715.4097869257779;720.6504845150098;662.777969380263;796.0288448093316;803.6984000356824;837.1126605797874;844.9831003379898;10320.587176513005;10794.711898228985;10884.29252098627;11035.889641103648;11046.944765276123;698.7938558125365;725.7568714480708;750.0961341583379;750.9061367329084;757.0083191019671;5015.874603134951;5153.5361817436615;5181.5854731621375;5226.189940559219;5289.418164001957;78722.6063869677;80330.5983470079;97540;101800;103665.48167259115;100706;101961.49019254856;104760;104856.47571762092;105347.4732626332;89583.55208224048;90258.54870725605;90696;93709;96303.51848240713;10482.186563778894;10684.679459616224;11174.888251117385;11382.94308528452;11455.656330310105;48478.5152148474;48659.75670121627;49105.75447122742;56011;57187;68511;79396;79492.60253698695;79629.20370796218;80847.5957620208;13081.542146024844;13364.732705346034;13542.79685804694;14113.717725645634;14991.025583337048;15642.687146257238;16639.251233694755;16661.833381666027;16695.415660452312;17791.555211119827;14335.139891606537;14935.701285974437;15176.089434633957;15188.620284492976;16229.513114606581;14108.435662573382;14630.634234143805;14646.780298295747;15287.235638217822;15339.23303834826;14184.858151418353;14452.927735361256;14840.7773883394;15138.470153544573;15215.847841521443;10690.893091069302;11332.886671133183;11441.256318339281;11496.709376529363;11723.472443739882;11417.543298267976;12180.051191809269;12940.288284144463;13047.151935124186;13064.934675326562 07:19:17 INFO - PROCESS | 12149 | __end_tp_report 07:19:17 INFO - PROCESS | 12149 | __start_cc_report 07:19:17 INFO - PROCESS | 12149 | _x_x_mozilla_cycle_collect,314 07:19:17 INFO - PROCESS | 12149 | __end_cc_report 07:19:17 INFO - PROCESS | 12149 | __startTimestamp1472739557663__endTimestamp 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | ------- Summary: start ------- 07:19:17 INFO - PROCESS | 12149 | Number of tests: 6 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#0] dojo.html Cycles:165 Average:5878.36 Median:6119.81 stddev:691.48 (11.3%) stddev-sans-first:663.86 07:19:17 INFO - PROCESS | 12149 | Values: 3321.3 3362.7 3369.4 3373.1 3394.1 5813.1 5861.3 5896.6 5927.3 5939.1 5816.8 5856.5 5937.2 5961.6 5975.1 5340.3 5358.9 5396.2 5432.1 5467.3 5338.0 5344.0 5370.9 5394.3 5403.7 5338.3 5350.3 5364.2 5368.6 5386.0 5430.9 5433.1 5439.3 5447.6 5480.4 5046.4 5189.5 5230.0 5323.1 5334.9 5790.0 5850.0 5863.6 5876.4 5913.9 6259.6 6291.7 6331.5 6358.0 6368.8 6134.7 6273.7 6286.9 6353.1 6376.1 5964.9 5972.2 5975.7 5980.7 6004.8 6423.7 6488.3 6492.8 6557.7 6560.8 6061.4 6125.8 6158.9 6297.0 6305.5 6319.6 6359.3 6378.1 6380.1 6405.1 6317.9 6319.4 6325.7 6331.9 6333.7 6081.5 6168.6 6172.8 6344.6 6355.0 4733.9 4734.6 4739.0 4758.4 4775.1 4250.6 4254.9 4267.7 4272.3 4292.6 5939.2 5974.8 5985.1 5996.0 6072.0 6346.6 6358.2 6381.1 6385.8 6386.6 6085.3 6094.1 6095.3 6096.9 6097.1 5357.6 5406.2 5433.5 5445.8 5490.1 6583.2 6637.4 6640.1 6642.6 6648.3 6368.6 6380.3 6397.2 6422.9 6437.2 6396.7 6484.2 6501.4 6572.1 6640.8 6281.2 6287.2 6306.8 6308.8 6314.2 6378.3 6405.4 6408.9 6409.2 6424.2 6118.6 6123.3 6140.1 6266.8 6287.6 6110.1 6141.2 6175.1 6177.8 6181.1 6130.4 6186.9 6194.9 6197.3 6199.4 6061.3 6119.8 6129.6 6248.6 6256.6 6093.9 6105.6 6128.4 6130.3 6137.4 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#1] ext.html Cycles:180 Average:68384.43 Median:45513.82 stddev:139693.07 (306.9%) stddev-sans-first:139992.21 07:19:17 INFO - PROCESS | 12149 | Values: 589.2 625.0 653.1 667.0 709.1 42082.6 47176.3 47282.8 47442.0 47484.0 47023.8 47602.3 47607.3 47695.8 47764.0 9026.9 9098.3 9163.8 9171.6 9182.8 9135.4 9177.9 9201.2 9222.6 9230.5 9239.1 9258.1 9269.0 9289.1 9297.2 9183.9 9193.5 9198.4 9204.6 9245.4 9197.4 9236.0 9252.4 9261.7 9285.6 44918.3 45069.3 45118.8 45236.8 45256.8 3653.2 3724.2 3829.3 3875.1 4042.7 34442.3 34679.8 34908.0 37898.8 38102.8 4665.3 4733.9 4761.2 4786.4 4789.0 381620.0 383321.0 383517.0 383841.0 387010.0 68269.3 68997.3 69171.0 69212.0 69509.0 39891.4 40227.2 40640.4 43580.6 72673.0 19449.4 19823.7 19827.2 19836.6 19850.6 14809.3 14887.7 14965.0 14976.2 14976.4 21146.5 21592.4 21618.2 21618.2 21637.4 7577.8 7622.9 7718.5 7722.2 7875.5 1002.4 1057.4 1128.8 1154.2 1191.1 34733.5 34793.7 34881.0 34889.7 35442.0 1128.3 1146.8 1176.3 1254.2 1282.9 17650.2 17740.2 18150.7 18429.7 18497.0 409774.0 649239.0 657004.0 664255.0 664575.0 64580.4 65041.7 65096.7 65232.3 65468.7 1432.0 1458.4 1477.4 1477.5 1509.6 35144.6 35831.1 36015.8 36016.5 36035.6 58432.4 58814.7 58823.0 59236.0 59336.1 538540.0 545995.0 548949.0 549434.0 550623.0 31789.7 32414.5 32562.5 32774.7 32789.2 52605.0 53456.5 54257.0 54589.5 54733.7 44918.3 45601.3 45847.8 46142.5 47376.5 44550.6 44736.3 45426.3 46488.8 46686.0 44771.1 45646.0 45740.3 46090.5 48140.3 38788.2 39145.4 39463.2 40691.4 40854.4 30246.9 31013.5 31546.5 31738.7 31865.7 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#2] jquery.html Cycles:180 Average:31421.16 Median:12315.99 stddev:89506.04 (726.7%) stddev-sans-first:89726.85 07:19:17 INFO - PROCESS | 12149 | Values: 413.8 417.7 433.2 434.3 445.0 19672.1 21267.3 21469.9 22451.9 22589.1 19478.5 19658.0 19776.8 19849.4 19902.6 5949.3 6148.0 6159.0 6198.3 6217.9 5743.5 6028.1 6056.7 6080.3 6122.1 6151.4 6155.8 6170.2 6170.9 6171.8 5972.6 6107.6 6124.1 6141.4 6144.5 5909.1 5924.3 5983.9 5986.2 6016.8 14791.8 15017.6 15037.5 15042.6 23570.8 2355.6 2474.1 2508.7 3035.3 3056.1 11099.4 13581.5 14382.9 15562.0 16087.8 1934.6 1956.7 1987.3 2020.2 2083.2 82194.6 82208.6 84557.0 85995.6 87003.6 11779.9 11852.5 11937.4 12061.1 12087.6 16877.1 16890.5 17008.9 17021.1 17029.9 8575.5 9117.0 9216.3 9305.8 9411.5 8746.5 8761.8 8890.3 9017.1 9066.5 4987.8 5072.2 5100.2 5201.1 5203.8 2107.2 2109.7 2123.2 2158.3 2180.1 944.9 1011.3 1027.8 1038.8 1057.7 20150.5 20450.0 20705.2 20789.5 21039.6 1033.6 1038.3 1073.2 1077.3 1089.6 10292.0 11014.9 11101.4 11365.5 11634.6 525770.0 542514.0 544016.0 545433.0 559369.0 95470.5 96689.0 98567.5 99149.0 101507.5 1019.8 1073.7 1116.8 1138.5 1140.6 17944.4 17947.3 18375.5 18635.3 19193.5 45602.0 46713.5 46876.0 46945.5 47751.3 61855.1 62601.0 62626.7 63466.4 63742.4 8951.0 9023.4 9173.7 9436.1 9458.2 13299.2 13476.9 13596.4 14109.5 14418.4 11911.5 12085.5 12115.8 12126.6 12136.3 12307.5 12348.3 12486.4 12550.9 12854.6 11979.0 12058.6 12091.2 12120.6 12158.2 9193.3 9242.9 9280.2 9294.4 9638.7 9046.7 11873.9 11885.3 12160.9 12195.8 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#3] mootools.html Cycles:180 Average:6321.98 Median:2737.24 stddev:3648.77 (133.3%) stddev-sans-first:3640.13 07:19:17 INFO - PROCESS | 12149 | Values: 1382.8 1474.3 1519.1 1684.3 1716.1 8301.8 9087.2 9191.9 9881.2 9888.3 9243.3 9518.1 9552.5 9615.7 9636.7 2524.1 2571.2 2573.1 2576.4 2579.3 2491.3 2531.0 2580.9 2581.5 2589.6 2499.9 2514.9 2516.1 2529.2 2537.3 2459.4 2488.6 2488.9 2507.2 2523.6 2453.4 2456.3 2461.1 2465.8 2475.2 9620.6 9793.8 9798.6 9825.6 9859.0 5551.8 5686.3 5687.2 5697.9 5706.3 3676.9 3795.7 3854.1 3887.3 3899.7 1241.6 1260.9 1278.6 1294.9 1324.0 13450.9 13585.9 13598.1 13614.3 13658.3 4945.2 4975.4 5101.9 5147.2 5200.0 11371.7 11394.8 11397.5 11417.2 11444.2 5768.4 5775.8 5799.3 5810.1 5813.7 4743.9 5145.2 5231.4 5282.6 5313.9 4291.8 4350.5 4397.6 4400.4 4402.3 1373.9 1379.8 1405.0 1414.4 1423.3 904.6 987.9 1055.9 1060.3 1061.0 6297.7 6333.5 6351.9 6387.7 6575.3 499.0 505.2 506.7 508.4 511.3 4120.7 4220.7 4240.9 4255.3 4274.6 11673.9 11830.0 11962.3 12046.6 12164.9 11388.5 11863.2 11890.3 11913.8 11938.1 8879.4 9010.6 9082.6 9084.7 9109.0 6515.4 6563.0 6725.5 6834.7 6900.5 10724.5 10972.3 11061.1 11152.7 11250.1 11143.2 11328.9 11340.5 11396.3 11491.3 8064.3 8194.4 8252.8 8452.6 8734.2 9440.0 9490.5 9559.7 9572.9 9574.2 8123.6 8396.0 8431.2 8470.7 8512.7 8135.2 8219.9 8274.8 8317.2 8337.3 7525.1 7659.3 7725.1 7769.7 7778.0 5439.0 5561.5 5598.2 5614.6 5753.7 5516.2 5527.3 5542.9 5592.9 5667.0 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#4] prototype.html Cycles:180 Average:4938.46 Median:5537.46 stddev:1422.59 (25.7%) stddev-sans-first:1384.16 07:19:17 INFO - PROCESS | 12149 | Values: 344.2 350.5 355.1 367.5 367.6 5589.0 5654.1 5781.6 5872.4 5916.3 5650.7 5699.9 5724.9 5726.5 5734.5 4522.7 4524.2 4538.4 4547.7 4550.2 4499.0 4510.5 4544.7 4549.3 4613.8 4453.5 4483.6 4495.1 4504.5 4514.5 4523.6 4533.6 4552.2 4561.8 4567.5 3885.3 3941.3 3993.8 4021.3 4030.4 5569.3 5573.0 5602.4 5607.9 5669.5 5030.5 5156.3 5161.8 5170.7 5219.3 5982.3 6036.9 6049.5 6055.5 6086.4 4301.9 4314.3 4338.6 4374.8 4381.8 6105.0 6348.0 6376.2 6393.9 6417.2 4442.8 4481.4 4489.3 4497.7 4504.2 4562.8 4564.0 4575.9 4578.0 4579.9 5017.2 5184.2 5190.6 5202.4 5220.0 5901.7 5984.5 6024.4 6041.3 6115.3 1533.5 1556.5 1558.6 1560.9 1565.2 973.3 988.8 1000.6 1005.7 1010.8 5176.4 5205.7 5212.7 5224.0 5238.4 5535.8 5542.4 5553.9 5577.2 5588.7 5084.7 5195.7 5296.3 5318.4 5318.4 2957.0 2973.7 2975.9 2984.8 3008.5 5699.8 5761.5 5773.7 5799.3 5799.7 5216.9 5568.5 5576.3 5659.4 5687.9 5732.4 5779.9 5800.5 5811.0 5815.5 5382.7 5384.9 5576.4 5591.4 5602.6 5414.8 5426.4 5508.7 5517.9 5539.2 5628.5 5667.5 5678.8 5702.7 5787.3 4053.6 4063.6 4376.7 4415.9 4423.2 6054.5 6198.5 6249.9 6268.0 6295.6 6064.9 6074.8 6078.8 6101.6 6103.6 6088.6 6101.8 6126.2 6130.6 6150.6 5815.0 6034.0 6077.2 6085.4 6091.0 6163.8 6169.7 6186.8 6190.9 6192.8 5779.9 5973.6 5988.4 6020.0 6040.4 07:19:17 INFO - PROCESS | 12149 | 07:19:17 INFO - PROCESS | 12149 | [#5] yui.html Cycles:180 Average:21592.95 Median:4171.00 stddev:29782.86 (714.0%) stddev-sans-first:29825.58 07:19:17 INFO - PROCESS | 12149 | Values: 825.2 833.8 888.4 891.4 907.0 16378.1 16810.0 17195.8 17416.5 17448.2 14934.7 14969.3 15050.8 15068.7 15331.4 721.5 868.9 887.1 889.1 891.6 881.0 884.6 887.6 892.6 896.3 940.0 943.0 943.8 946.0 947.1 928.0 930.7 934.4 936.7 938.4 686.6 689.5 691.4 692.4 695.5 15851.2 15889.3 15920.4 15930.5 15949.2 15171.8 15193.5 15509.3 15539.9 15607.4 13233.5 13237.7 13312.2 13416.5 13534.9 4150.8 4176.3 4189.4 4257.2 4263.8 89266.6 89624.6 90867.5 91052.0 91106.5 12448.7 12576.2 12692.9 12752.2 12803.5 23667.4 23792.8 23800.0 24109.2 24179.4 8844.1 8890.1 9055.3 9121.4 9141.4 5078.4 5136.2 5208.7 5229.8 5337.8 4051.2 4132.7 4165.7 4179.0 4181.9 692.9 695.5 700.7 715.4 720.7 662.8 796.0 803.7 837.1 845.0 10320.6 10794.7 10884.3 11035.9 11046.9 698.8 725.8 750.1 750.9 757.0 5015.9 5153.5 5181.6 5226.2 5289.4 78722.6 80330.6 97540.0 101800.0 103665.5 100706.0 101961.5 104760.0 104856.5 105347.5 89583.6 90258.5 90696.0 93709.0 96303.5 10482.2 10684.7 11174.9 11382.9 11455.7 48478.5 48659.8 49105.8 56011.0 57187.0 68511.0 79396.0 79492.6 79629.2 80847.6 13081.5 13364.7 13542.8 14113.7 14991.0 15642.7 16639.3 16661.8 16695.4 17791.6 14335.1 14935.7 15176.1 15188.6 16229.5 14108.4 14630.6 14646.8 15287.2 15339.2 14184.9 14452.9 14840.8 15138.5 15215.8 10690.9 11332.9 11441.3 11496.7 11723.5 11417.5 12180.1 12940.3 13047.2 13064.9 07:19:17 INFO - PROCESS | 12149 | -------- Summary: end -------- 07:19:17 INFO - PROCESS | 12149 | 07:19:18 INFO - TEST-INFO | 12149: exit 0 07:19:18 INFO - TEST-OK | dromaeo_css | took 1111781ms 07:19:18 INFO - TEST-START | kraken 07:19:18 INFO - operating with platform_type : linux_ 07:19:18 INFO - Initialising browser for kraken test... 07:19:18 INFO - TEST-INFO | started process 12419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8sK_5/profile http://localhost:44576/getInfo.html) 07:19:23 INFO - PROCESS | 12419 | __metrics Screen width/height:1600/1200 07:19:23 INFO - PROCESS | 12419 | colorDepth:24 07:19:23 INFO - PROCESS | 12419 | Browser inner width/height: 1024/697 07:19:23 INFO - PROCESS | 12419 | __metrics 07:19:23 ERROR - PROCESS | 12419 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:24 INFO - TEST-INFO | 12419: exit 0 07:19:24 INFO - Browser initialized. 07:19:24 INFO - Running cycle 1/1 for kraken test... 07:19:24 INFO - TEST-INFO | started process 12547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8sK_5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:36 INFO - PROCESS | 12547 | RSS: Main: 152334336 07:19:36 INFO - PROCESS | 12547 | 07:19:38 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44576/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:39 INFO - PROCESS | 12547 | RSS: Main: 157638656 07:19:39 INFO - PROCESS | 12547 | 07:19:42 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44576/tests/kraken/driver.html?testName=audio-dft) 07:19:43 INFO - PROCESS | 12547 | RSS: Main: 156479488 07:19:43 INFO - PROCESS | 12547 | 07:19:45 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44576/tests/kraken/driver.html?testName=audio-fft) 07:19:46 INFO - PROCESS | 12547 | RSS: Main: 156905472 07:19:46 INFO - PROCESS | 12547 | 07:19:48 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44576/tests/kraken/driver.html?testName=audio-oscillator) 07:19:49 INFO - PROCESS | 12547 | RSS: Main: 157306880 07:19:49 INFO - PROCESS | 12547 | 07:19:51 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44576/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:52 INFO - PROCESS | 12547 | RSS: Main: 156680192 07:19:52 INFO - PROCESS | 12547 | 07:19:55 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44576/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:55 INFO - PROCESS | 12547 | RSS: Main: 156008448 07:19:55 INFO - PROCESS | 12547 | 07:19:59 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44576/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:59 INFO - PROCESS | 12547 | RSS: Main: 155197440 07:19:59 INFO - PROCESS | 12547 | 07:20:02 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44576/tests/kraken/driver.html?testName=json-parse-financial) 07:20:03 INFO - PROCESS | 12547 | RSS: Main: 156430336 07:20:03 INFO - PROCESS | 12547 | 07:20:04 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44576/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:05 INFO - PROCESS | 12547 | RSS: Main: 156540928 07:20:05 INFO - PROCESS | 12547 | 07:20:07 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:07 INFO - PROCESS | 12547 | RSS: Main: 156958720 07:20:07 INFO - PROCESS | 12547 | 07:20:09 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:10 INFO - PROCESS | 12547 | RSS: Main: 156884992 07:20:10 INFO - PROCESS | 12547 | 07:20:12 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:13 INFO - PROCESS | 12547 | RSS: Main: 157306880 07:20:13 INFO - PROCESS | 12547 | 07:20:16 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:16 INFO - PROCESS | 12547 | RSS: Main: 157523968 07:20:16 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:44576/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44576/tests/kraken/driver.html?testName=ai-astar) 07:20:18 INFO - PROCESS | 12547 | RSS: Main: 158167040 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | __start_tp_report 07:20:18 INFO - PROCESS | 12547 | _x_x_mozilla_page_load 07:20:18 INFO - PROCESS | 12547 | _x_x_mozilla_page_load_details 07:20:18 INFO - PROCESS | 12547 | |i|pagename|runs| 07:20:18 INFO - PROCESS | 12547 | |0;ai-astar;100;113;105;112;111;112;109;110;108;109 07:20:18 INFO - PROCESS | 12547 | |1;audio-beat-detection;147;115;143;173;121;148;146;117;185;117 07:20:18 INFO - PROCESS | 12547 | |2;audio-dft;162;161;162;162;162;162;164;162;165;162 07:20:18 INFO - PROCESS | 12547 | |3;audio-fft;75;75;75;169;75;76;75;75;165;75 07:20:18 INFO - PROCESS | 12547 | |4;audio-oscillator;85;84;156;84;84;83;143;83;83;142 07:20:18 INFO - PROCESS | 12547 | |5;imaging-gaussian-blur;117;117;117;117;118;117;118;117;117;118 07:20:18 INFO - PROCESS | 12547 | |6;imaging-darkroom;146;149;148;147;146;147;147;147;147;148 07:20:18 INFO - PROCESS | 12547 | |7;imaging-desaturate;95;96;105;95;96;95;96;98;95;96 07:20:18 INFO - PROCESS | 12547 | |8;json-parse-financial;51;51;53;51;51;52;52;52;51;53 07:20:18 INFO - PROCESS | 12547 | |9;json-stringify-tinderbox;59;60;59;59;59;59;59;58;59;60 07:20:18 INFO - PROCESS | 12547 | |10;stanford-crypto-aes;72;73;71;73;73;78;70;73;72;75 07:20:18 INFO - PROCESS | 12547 | |11;stanford-crypto-ccm;118;122;118;119;126;131;117;118;160;127 07:20:18 INFO - PROCESS | 12547 | |12;stanford-crypto-pbkdf2;158;159;156;156;153;156;157;157;154;154 07:20:18 INFO - PROCESS | 12547 | |13;stanford-crypto-sha256-iterative;63;64;62;62;63;61;62;60;60;61 07:20:18 INFO - PROCESS | 12547 | __end_tp_report 07:20:18 INFO - PROCESS | 12547 | __start_cc_report 07:20:18 INFO - PROCESS | 12547 | _x_x_mozilla_cycle_collect,893 07:20:18 INFO - PROCESS | 12547 | __end_cc_report 07:20:18 INFO - PROCESS | 12547 | __startTimestamp1472739618789__endTimestamp 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | ------- Summary: start ------- 07:20:18 INFO - PROCESS | 12547 | Number of tests: 14 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.50 stddev:3.90 (3.5%) stddev-sans-first:2.47 07:20:18 INFO - PROCESS | 12547 | Values: 100.0 113.0 105.0 112.0 111.0 112.0 109.0 110.0 108.0 109.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:146.50 stddev:24.27 (16.6%) stddev-sans-first:25.65 07:20:18 INFO - PROCESS | 12547 | Values: 147.0 115.0 143.0 173.0 121.0 148.0 146.0 117.0 185.0 117.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 07:20:18 INFO - PROCESS | 12547 | Values: 162.0 161.0 162.0 162.0 162.0 162.0 164.0 162.0 165.0 162.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#3] audio-fft Cycles:10 Average:93.50 Median:75.00 stddev:38.75 (51.7%) stddev-sans-first:40.52 07:20:18 INFO - PROCESS | 12547 | Values: 75.0 75.0 75.0 169.0 75.0 76.0 75.0 75.0 165.0 75.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.50 stddev:30.80 (36.9%) stddev-sans-first:31.99 07:20:18 INFO - PROCESS | 12547 | Values: 85.0 84.0 156.0 84.0 84.0 83.0 143.0 83.0 83.0 142.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:20:18 INFO - PROCESS | 12547 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 117.0 118.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 07:20:18 INFO - PROCESS | 12547 | Values: 146.0 149.0 148.0 147.0 146.0 147.0 147.0 147.0 147.0 148.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.00 stddev:3.06 (3.2%) stddev-sans-first:3.18 07:20:18 INFO - PROCESS | 12547 | Values: 95.0 96.0 105.0 95.0 96.0 95.0 96.0 98.0 95.0 96.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 07:20:18 INFO - PROCESS | 12547 | Values: 51.0 51.0 53.0 51.0 51.0 52.0 52.0 52.0 51.0 53.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 07:20:18 INFO - PROCESS | 12547 | Values: 59.0 60.0 59.0 59.0 59.0 59.0 59.0 58.0 59.0 60.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:2.21 (3.0%) stddev-sans-first:2.32 07:20:18 INFO - PROCESS | 12547 | Values: 72.0 73.0 71.0 73.0 73.0 78.0 70.0 73.0 72.0 75.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:124.00 stddev:12.99 (10.5%) stddev-sans-first:13.48 07:20:18 INFO - PROCESS | 12547 | Values: 118.0 122.0 118.0 119.0 126.0 131.0 117.0 118.0 160.0 127.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:156.50 stddev:1.89 (1.2%) stddev-sans-first:1.86 07:20:18 INFO - PROCESS | 12547 | Values: 158.0 159.0 156.0 156.0 153.0 156.0 157.0 157.0 154.0 154.0 07:20:18 INFO - PROCESS | 12547 | 07:20:18 INFO - PROCESS | 12547 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.32 (2.1%) stddev-sans-first:1.32 07:20:18 INFO - PROCESS | 12547 | Values: 63.0 64.0 62.0 62.0 63.0 61.0 62.0 60.0 60.0 61.0 07:20:18 INFO - PROCESS | 12547 | -------- Summary: end -------- 07:20:18 INFO - PROCESS | 12547 | 07:20:19 INFO - TEST-INFO | 12547: exit 0 07:20:19 INFO - TEST-OK | kraken | took 61321ms 07:20:19 INFO - SUITE-END | took 1173s 07:20:19 INFO - Completed test suite (00:19:33) 07:20:19 INFO - javascript benchmark 07:20:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7824.102943943124, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.2859235264414, 3362.680545348191, 3369.39350916835, 3373.139849338418, 3394.0666316762904, 5813.098969659704, 5861.296644402678, 5896.616719913213, 5927.2590926134235, 5939.109133629963, 5816.767329306833, 5856.502197313233, 5937.198478205441, 5961.61249518781, 5975.073863551156, 5340.279062326585, 5358.919616205741, 5396.244525766396, 5432.076546987022, 5467.289252397183, 5338.0, 5344.0, 5370.892582148354, 5394.32570928634, 5403.729813509309, 5338.27933229015, 5350.27771250882, 5364.195370694408, 5368.570514358861, 5386.030514507387, 5430.864228394283, 5433.1035379162395, 5439.292891924025, 5447.591430642698, 5480.369757477862, 5046.419661738911, 5189.532942035235, 5229.973850130763, 5323.121684921984, 5334.893302133974, 5790.0, 5849.970750146222, 5863.560232982501, 5876.382979787104, 5913.852153696192, 6259.593126446812, 6291.6854157291955, 6331.4934805215935, 6358.0, 6368.777092801776, 6134.681512609229, 6273.7176827042895, 6286.87426251477, 6353.142325786006, 6376.107344971708, 5964.850878728023, 5972.163897054415, 5975.701214939279, 5980.700964951735, 6004.78983235589, 6423.66729565695, 6488.28628850826, 6492.837679058061, 6557.704903279364, 6560.770373036921, 6061.36355682647, 6125.815438131368, 6158.938410615926, 6296.968515157395, 6305.464035556937, 6319.58922670025, 6359.300476947532, 6378.075179099004, 6380.0748891410485, 6405.071264666597, 6317.936820631827, 6319.368063193644, 6325.65208913505, 6331.905021424683, 6333.714982825784, 6081.483073938676, 6168.568200226031, 6172.753089876444, 6344.587601805868, 6355.046742988588, 4733.905321893543, 4734.573888350065, 4738.976305118521, 4758.405199350082, 4775.06886157196, 4250.55369186231, 4254.893627659333, 4267.679924005712, 4272.29507131326, 4292.570742925683, 5939.198208241918, 5974.761009559656, 5985.102234664835, 5995.970020149872, 6071.9696401518595, 6346.587471814318, 6358.205224346957, 6381.074744162071, 6385.840353991094, 6386.616802991835, 6085.3306136325855, 6094.085887116879, 6095.299040610386, 6096.939030609726, 6097.146399504073, 5357.598180136505, 5406.243125962368, 5433.4566543345345, 5445.759339327629, 5490.094134467848, 6583.177102862142, 6637.369449902251, 6640.136782218281, 6642.63465509392, 6648.335166483313, 6368.649724265025, 6380.330065343258, 6397.168368112116, 6422.935770642233, 6437.195350581177, 6396.71214795326, 6484.1894763154605, 6501.414872661483, 6572.14562106923, 6640.800775976729, 6281.214848143982, 6287.182666253358, 6306.810795676137, 6308.810735677937, 6314.242290925118, 6378.298387177314, 6405.423511883952, 6408.903866441914, 6409.198850143732, 6424.229092508742, 6118.60229085108, 6123.265208174869, 6140.078988151813, 6266.843328916631, 6287.559870808999, 6110.072772100023, 6141.201643786279, 6175.073738939195, 6177.783777567944, 6181.134641150153, 6130.448259656652, 6186.876262474635, 6194.876102478015, 6197.25632924052, 6199.442050215503, 6061.302950160788, 6119.807225076679, 6129.633877546871, 6248.593841400295, 6256.6246025236815, 6093.9085913712215, 6105.572609917263, 6128.448439640454, 6130.295016073209, 6137.3862613738265], "value": 5829.671916830774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.2192844481064, 624.9531285153613, 653.1378580274037, 666.9899951500728, 709.1171491493092, 42082.57917420829, 47176.292355614605, 47282.76358618207, 47442.0, 47484.0, 47023.76488117563, 47602.285965710544, 47607.28589071158, 47695.761521192435, 47764.0, 9026.909730902706, 9098.31762617803, 9163.816723665523, 9171.633134674605, 9182.816343673123, 9135.36052476327, 9177.908220917772, 9201.217896478807, 9222.631094756201, 9230.492322922237, 9239.076092390775, 9258.120478554527, 9268.953655231748, 9289.117533834275, 9297.163255307021, 9183.862242066374, 9193.494357810318, 9198.356115071914, 9204.631814727403, 9245.445273283625, 9197.356185067049, 9235.953820230856, 9252.398594091363, 9261.722148335493, 9285.628574856997, 44918.32622510665, 45069.323960140624, 45118.77440612809, 45236.77381613104, 45256.77371613121, 3653.214558869829, 3724.1992971511254, 3829.272438236726, 3875.0506125999027, 4042.7170098093147, 34442.31115377678, 34679.826600866836, 34908.0, 37898.81050594757, 38102.80948595267, 4665.2768820832825, 4733.928991065137, 4761.166795810719, 4786.353842231289, 4789.0, 381620.0, 383321.0, 383517.0, 383841.0, 387010.0, 68269.31730682729, 68997.31002690009, 69171.0, 69212.0, 69509.0, 39891.40162897559, 40227.19545609071, 40640.39039414411, 43580.56419435828, 72673.0, 19449.4165175045, 19823.702644460343, 19827.206911723657, 19836.60326793456, 19850.60298794045, 14809.259537023107, 14887.70224595502, 14965.0, 14976.17631030282, 14976.400943962119, 21146.471338216666, 21592.35222943314, 21618.243361482273, 21618.243361482586, 21637.35087947364, 7577.772666820004, 7622.923770762332, 7718.498297610749, 7722.150563438017, 7875.527468351917, 1002.4035698759227, 1057.4448414582405, 1128.836318733779, 1154.163231657042, 1191.094767976327, 34733.47899781505, 34793.652063479545, 34881.0, 34889.65110348864, 35442.0, 1128.2948157401624, 1146.7935771561033, 1176.2707121584672, 1254.2349167008117, 1282.8845403913692, 17650.205740741698, 17740.20169092394, 18150.727739083923, 18429.723554146698, 18497.0, 409774.0, 649239.0, 657004.0, 664255.0, 664575.0, 64580.35419645837, 65041.67479162574, 65096.674516627114, 65232.34767652263, 65468.67265663641, 1432.0476882872981, 1458.4385011770332, 1477.409036385433, 1477.541961991786, 1509.6150481627114, 35144.64855351414, 35831.10422239361, 36015.81992090023, 36016.4597531032, 36035.63964360428, 58432.4156758427, 58814.705926470095, 58823.0, 59236.0, 59336.109958351524, 538540.0, 545995.0, 548949.0, 549434.0, 550623.0, 31789.68210317867, 32414.51378229281, 32562.51156232611, 32774.67225327716, 32789.18027049248, 52605.0, 53456.465435346705, 54257.0, 54589.45410546003, 54733.72633136809, 44918.3262251073, 45601.31598025966, 45847.77076114598, 46142.538574615166, 47376.52623473721, 44550.55449445464, 44736.32895506505, 45426.31860522161, 46488.767556162005, 46686.0, 44771.10457790891, 45646.0, 45740.31389529226, 46090.539094608626, 48140.27789583229, 38788.224235514564, 39145.41281880831, 39463.210735785695, 40691.389629156176, 40854.38718419167, 30246.904389686915, 31013.534796978514, 31546.526802097527, 31738.682613173572, 31865.6813431872], "value": 21975.173283153094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.842739758892, 417.7121282999967, 433.198582622149, 434.3289617540899, 444.9577290157436, 19672.11475483603, 21267.25564605237, 21469.892650536745, 22451.887740561273, 22589.096436142576, 19478.513037174078, 19658.01709914502, 19776.802231977676, 19849.404517864452, 19902.60194796107, 5949.286085669725, 6148.0, 6159.045347971071, 6198.287196972361, 6217.937820621803, 5743.483086522212, 6028.125921741344, 6056.727447264883, 6080.331163572026, 6122.142899994004, 6151.384861513858, 6155.784547540837, 6170.228721409824, 6170.938290617104, 6171.783987560436, 5972.611780234292, 6107.603005804631, 6124.050772130327, 6141.4472697456995, 6144.539159563028, 5909.143174239754, 5924.348321684612, 5983.850403739898, 5986.161937328778, 6016.759329626853, 14791.778123328158, 15017.624559385993, 15037.548873533702, 15042.623934401618, 23570.764292357027, 2355.587772139869, 2474.0969546115716, 2508.7240403555593, 3035.2867076237067, 3056.0526236866644, 11099.38953357565, 13581.456741730319, 14382.92808535961, 15562.0, 16087.758683619755, 1934.642091213116, 1956.6967120096408, 1987.2647120565339, 2020.1919232307196, 2083.197968782015, 82194.58902705507, 82208.58895705483, 84556.99999999939, 85995.57002214949, 87003.56498217532, 11779.941100294443, 11852.466639001266, 11937.403129843648, 12061.095417843697, 12087.576934807239, 16877.071761053016, 16890.493285201464, 17008.914955425393, 17021.063841488838, 17029.91485042567, 8575.485470871768, 9116.954415227881, 9216.262698984065, 9305.767355816159, 9411.529423528933, 8746.475211487332, 8761.765749733904, 8890.288776897833, 9017.143371379709, 9066.54667266634, 4987.825426110068, 5072.239164125337, 5100.158473851773, 5201.141811601044, 5203.765830537559, 2107.167668770827, 2109.7215007791547, 2123.2144106680626, 2158.276977212651, 2180.0843645668856, 944.9244060475146, 1011.3426272922659, 1027.8047171037479, 1038.8493668418037, 1057.6668349469892, 20150.496237593885, 20450.0, 20705.23779530686, 20789.480262993242, 21039.579208415747, 1033.6227277043824, 1038.3250886923408, 1073.2058276875057, 1077.256692881909, 1089.5696200000955, 10292.0, 11014.944925275322, 11101.389423581619, 11365.545378184945, 11634.592789252336, 525770.0, 542514.0, 544016.0, 545433.0, 559369.0, 95470.52264738771, 96689.0, 98567.50716246373, 99149.0, 101507.49246253721, 1019.7756493571402, 1073.7154654016847, 1116.8045592021365, 1138.4990604134155, 1140.5836869542557, 17944.371946982315, 17947.282108715506, 18375.54061148482, 18635.347762828234, 19193.52016199606, 45602.0, 46713.53286467092, 46876.0, 46945.53054469548, 47751.28373074337, 61855.07217391833, 62601.0, 62626.686866565375, 63466.36533634605, 63742.36257637364, 8951.005806461171, 9023.368364214442, 9173.724788256362, 9436.056394360783, 9458.24334053288, 13299.20204787716, 13476.93261533686, 13596.388162532512, 14109.506167284097, 14418.42326306936, 11911.463984120563, 12085.516579336729, 12115.75768484643, 12126.575569855013, 12136.33250171249, 12307.507699691912, 12348.259104453762, 12486.438110284877, 12550.87449125497, 12854.614361569167, 11979.0, 12058.638240852437, 12091.153619246568, 12120.575779847662, 12158.209716368408, 9193.310501712533, 9242.907570924204, 9280.164785169367, 9294.442333460014, 9638.710838674851, 9046.738315421144, 11873.940630296793, 11885.28688278706, 12160.939195304321, 12195.756084878076], "value": 9840.647656187177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.7856682214253, 1474.3365485531513, 1519.0961377980097, 1684.3094331324153, 1716.081896185541, 8301.792455188623, 9087.227585655219, 9191.862122068174, 9881.16010139138, 9888.307818452711, 9243.306751993594, 9518.14336709696, 9552.522373881313, 9615.711528654134, 9636.662716804913, 2524.129175434472, 2571.241483762285, 2573.086554273234, 2576.4331846993723, 2579.2778022153734, 2491.289982355029, 2530.987345063272, 2580.870956452179, 2581.5224183526107, 2589.5727205011244, 2499.9250022499264, 2514.88683009265, 2516.119358224616, 2529.165375426114, 2537.251510804308, 2459.3974476253343, 2488.5645012122895, 2488.8880000399936, 2507.1976967370447, 2523.558377283968, 2453.362125847275, 2456.250843492734, 2461.052494789501, 2465.827392082555, 2475.1708177760293, 9620.615175392975, 9793.755156121082, 9798.608055677765, 9825.557849896772, 9858.950705246429, 5551.805686800983, 5686.289213848269, 5687.203791469197, 5697.914531282034, 5706.315242170925, 3676.926461470782, 3795.734298599099, 3854.0557563396865, 3887.261420330128, 3899.7270191086636, 1241.5778635263964, 1260.8802163794426, 1278.5972418688084, 1294.922304661714, 1324.040070948564, 13450.93274533631, 13585.86414135856, 13598.11612245201, 13614.251216183105, 13658.317084145752, 4945.208766597327, 4975.427825800007, 5101.948980510221, 5147.176451767699, 5199.974000130051, 11371.658850234506, 11394.772104557862, 11397.487113079931, 11417.20079594437, 11444.198906076661, 5768.394318596571, 5775.768969241268, 5799.3040835100055, 5810.12848072784, 5813.709314534256, 4743.881402964885, 5145.151050076695, 5231.372235331784, 5282.550983166474, 5313.920291195635, 4291.785410729513, 4350.499692535335, 4397.560243975577, 4400.44994375703, 4402.25161722508, 1373.9450421983208, 1379.7723375643106, 1405.0, 1414.370605080729, 1423.3168079321983, 904.6426661468814, 987.9429010958349, 1055.8574592430077, 1060.3054998975683, 1061.0450594465044, 6297.653629050446, 6333.524985626096, 6351.904721429182, 6387.712552935129, 6575.342465753482, 499.0493110624267, 505.19673718787305, 506.70104638262865, 508.40516595583256, 511.3173912826344, 4120.670346372343, 4220.746755194698, 4240.851570195028, 4255.276602977501, 4274.5939135782055, 11673.883261167279, 11830.0, 11962.342071185994, 12046.638600841989, 12164.878351216552, 11388.487518061707, 11863.16957812962, 11890.28658280506, 11913.821292680617, 11938.104642151873, 8879.422837515542, 9010.59452324647, 9082.636694532277, 9084.682036128705, 9109.0, 6515.381038801306, 6563.0, 6725.461963042847, 6834.658267086726, 6900.482463815334, 10724.463776810971, 10972.341659500455, 11061.11511079128, 11152.721181970515, 11250.099992000783, 11143.164262680493, 11328.886711132784, 11340.546378144782, 11396.259243149168, 11491.310521368745, 8064.274215320649, 8194.385421093322, 8252.83494330122, 8452.577371131541, 8734.170253825876, 9439.952800235955, 9490.525473726148, 9559.665411710557, 9572.856407153758, 9574.186194173573, 8123.593820309079, 8396.0, 8431.241188293083, 8470.66117355299, 8512.744617661232, 8135.227153420416, 8219.917800821915, 8274.793130171553, 8317.209865062809, 8337.333013359039, 7525.059367579052, 7659.272369124924, 7725.072991240862, 7769.689212431439, 7777.988861447976, 5438.972805135949, 5561.5272701820795, 5598.244237027949, 5614.606977511535, 5753.683547404766, 5516.227728117987, 5527.2538207341495, 5542.946840100368, 5592.860178495407, 5667.036603777286], "value": 4955.554230471325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.23064450952126, 350.5040367879451, 355.1051350596497, 367.48919002586393, 367.61951380321375, 5589.0, 5654.123610840326, 5781.595288329813, 5872.412758724131, 5916.319623243328, 5650.74571644276, 5699.943000569993, 5724.856878578038, 5726.484616384525, 5734.455226753452, 4522.706024108439, 4524.185646583614, 4538.410006699141, 4547.681662283641, 4550.226461501553, 4498.955010449887, 4510.526394728556, 4544.7045942013665, 4549.340345649879, 4613.838515651953, 4453.51011388747, 4483.618892394151, 4495.056038231975, 4504.4594648642205, 4514.503404625489, 4523.638108951292, 4533.639908027611, 4552.203364411248, 4561.771911404417, 4567.543245675439, 3885.320068987944, 3941.2905676978135, 3993.7803420811842, 4021.336479480882, 4030.415589739471, 5569.331680198402, 5572.972135139298, 5602.38373778888, 5607.915881261784, 5669.546436285129, 5030.471800460972, 5156.252343410222, 5161.793528258865, 5170.715610641413, 5219.347581552306, 5982.341942386334, 6036.879262414727, 6049.48579370754, 6055.455009049163, 6086.360932102154, 4301.870943871689, 4314.309710446345, 4338.631216346615, 4374.759388233646, 4381.802818873127, 6104.96947515264, 6347.968260158716, 6376.234851817765, 6393.93606063938, 6417.197850268716, 4442.844500442469, 4481.395011673448, 4489.281714925596, 4497.730136191773, 4504.234280172379, 4562.771861406983, 4563.995920897397, 4575.9084818303445, 4577.977110114494, 4579.931301030487, 5017.197248440232, 5184.196449550326, 5190.6107041972, 5202.4277329493725, 5219.982103489859, 5901.675407852609, 5984.521238300926, 6024.433647251746, 6041.2750469943885, 6115.266168059861, 1533.4709525214007, 1556.5408204579676, 1558.6103474131467, 1560.9151639610361, 1565.2017471089825, 973.255459573427, 988.8269552828226, 1000.6497725795913, 1005.7133716890651, 1010.777628921636, 5176.404713457925, 5205.7397130143345, 5212.687238765686, 5223.973880130575, 5238.371395432572, 5535.750891209906, 5542.392706115262, 5553.916691249554, 5577.247071645357, 5588.6646801192055, 5084.745762711849, 5195.714235716996, 5296.25852380667, 5318.393986946368, 5318.414974352818, 2957.038962337249, 2973.721299841046, 2975.8691697155377, 2984.7612191024664, 3008.4807172378246, 5699.772009119672, 5761.452661997104, 5773.682447465346, 5799.2750906136735, 5799.681017543991, 5216.869578260574, 5568.524341049625, 5576.275084239023, 5659.377468478464, 5687.914681279784, 5732.426757324234, 5779.88440231193, 5800.53595712342, 5810.970945145163, 5815.505682017076, 5382.703951282717, 5384.919226211531, 5576.442355764553, 5591.440855914376, 5602.59935016246, 5414.810481633124, 5426.375966763872, 5508.697021663847, 5517.917231241454, 5539.17207321591, 5628.521575665951, 5667.518260947701, 5678.77284908599, 5702.692438385643, 5787.276590426197, 4053.5946405359227, 4063.6342729154517, 4376.715513491613, 4415.867523974286, 4423.16438677945, 6054.455099041107, 6198.473129783839, 6249.875022495994, 6268.0, 6295.622262664255, 6064.878702426015, 6074.817755467343, 6078.787242446493, 6101.60339577926, 6103.572749907641, 6088.573799833969, 6101.786437474667, 6126.172966649446, 6130.570860039755, 6150.630962142286, 5814.970925145347, 6033.969830150997, 6077.179580756718, 6085.391460853879, 6090.969545152246, 6163.7842675506145, 6169.691515424301, 6186.81439556814, 6190.907136392868, 6192.783252586138, 5779.930712818142, 5973.610555777724, 5988.431099045583, 6019.969900150471, 6040.369100342795], "value": 4485.938580839939, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.2201669422394, 833.7790485521334, 888.3603805260212, 891.3715830339611, 907.02947845805, 16378.099204543745, 16810.0, 17195.828041719593, 17416.47750567482, 17448.214830332632, 14934.701305973875, 14969.251537423139, 15050.774238386433, 15068.719158871509, 15331.386744530233, 721.4913485992363, 868.9131086891324, 887.1128871128872, 889.1242126505387, 891.5809569502323, 880.9911900880974, 884.5975081338008, 887.6493784954939, 892.5983307511614, 896.2964073202537, 939.9906000940006, 942.9528523573828, 943.7640589852537, 946.0397696338222, 947.0671388682142, 927.9860802087974, 930.6603089872216, 934.4346670264495, 936.7143021378477, 938.4041133879967, 686.6086330791451, 689.4759982413352, 691.353584398589, 692.4425837201051, 695.5166159519136, 15851.207439627973, 15889.284982175714, 15920.442784502602, 15930.522084337488, 15949.20253987296, 15171.848281517154, 15193.468228612057, 15509.302081406364, 15539.922300388538, 15607.367823984296, 13233.536826211037, 13237.669058273525, 13312.156945006249, 13416.5304214353, 13534.864651353459, 4150.813213405404, 4176.331786914079, 4189.392538081992, 4257.233697934371, 4263.8081286342185, 89266.55366723124, 89624.55187724085, 90867.54566227192, 91052.0, 91106.5444672779, 12448.688782780322, 12576.182548134342, 12692.873071269169, 12752.171108877894, 12803.487860485477, 23667.408314791923, 23792.762072379403, 23800.04799808023, 24109.15617953363, 24179.39551511191, 8844.071372505923, 8890.110988901188, 9055.320850936207, 9121.407108537924, 9141.360104792733, 5078.415982162099, 5136.1525348317855, 5208.661437006658, 5229.843104706865, 5337.839864804062, 4051.210014047291, 4132.73137246078, 4165.66674666032, 4178.979105104455, 4181.916361672749, 692.9064576282238, 695.4818660098259, 700.7127077898037, 715.4097869257779, 720.6504845150098, 662.777969380263, 796.0288448093316, 803.6984000356824, 837.1126605797874, 844.9831003379898, 10320.587176513005, 10794.711898228985, 10884.29252098627, 11035.889641103648, 11046.944765276123, 698.7938558125365, 725.7568714480708, 750.0961341583379, 750.9061367329084, 757.0083191019671, 5015.874603134951, 5153.5361817436615, 5181.5854731621375, 5226.189940559219, 5289.418164001957, 78722.6063869677, 80330.5983470079, 97540.0, 101800.0, 103665.48167259115, 100706.0, 101961.49019254856, 104760.0, 104856.47571762092, 105347.4732626332, 89583.55208224048, 90258.54870725605, 90696.0, 93709.0, 96303.51848240713, 10482.186563778894, 10684.679459616224, 11174.888251117385, 11382.94308528452, 11455.656330310105, 48478.5152148474, 48659.75670121627, 49105.75447122742, 56011.0, 57187.0, 68511.0, 79396.0, 79492.60253698695, 79629.20370796218, 80847.5957620208, 13081.542146024844, 13364.732705346034, 13542.79685804694, 14113.717725645634, 14991.025583337048, 15642.687146257238, 16639.251233694755, 16661.833381666027, 16695.415660452312, 17791.555211119827, 14335.139891606537, 14935.701285974437, 15176.089434633957, 15188.620284492976, 16229.513114606581, 14108.435662573382, 14630.634234143805, 14646.780298295747, 15287.235638217822, 15339.23303834826, 14184.858151418353, 14452.927735361256, 14840.7773883394, 15138.470153544573, 15215.847841521443, 10690.893091069302, 11332.886671133183, 11441.256318339281, 11496.709376529363, 11723.472443739882, 11417.543298267976, 12180.051191809269, 12940.288284144463, 13047.151935124186, 13064.934675326562], "value": 8185.002679099968, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 105.0, 112.0, 111.0, 112.0, 109.0, 110.0, 108.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 143.0, 173.0, 121.0, 148.0, 146.0, 117.0, 185.0, 117.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 164.0, 162.0, 165.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 169.0, 75.0, 76.0, 75.0, 75.0, 165.0, 75.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 156.0, 84.0, 84.0, 83.0, 143.0, 83.0, 83.0, 142.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 105.0, 95.0, 96.0, 95.0, 96.0, 98.0, 95.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 51.0, 51.0, 52.0, 52.0, 52.0, 51.0, 53.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 71.0, 73.0, 73.0, 78.0, 70.0, 73.0, 72.0, 75.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 118.0, 119.0, 126.0, 131.0, 117.0, 118.0, 160.0, 127.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 156.0, 156.0, 153.0, 156.0, 157.0, 157.0, 154.0, 154.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 62.0, 63.0, 61.0, 62.0, 60.0, 60.0, 61.0], "value": 61.8, "unit": "score"}]}]} 07:20:20 INFO - Return code: 0 07:20:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:20 ERROR - # TBPL SUCCESS # 07:20:20 INFO - Running post-action listener: _resource_record_post_action 07:20:20 INFO - [mozharness: 2016-09-01 14:20:20.132000Z] Finished run-tests step (success) 07:20:20 INFO - Running post-run listener: _resource_record_post_run 07:20:20 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 325169152; Read time: 28; Write time: 278936 07:20:20 INFO - TinderboxPrint: CPU usage
13.3% 07:20:20 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 07:20:20 INFO - TinderboxPrint: I/O write bytes / time
325,169,152 / 278,936 07:20:20 INFO - TinderboxPrint: CPU idle
8,229.2 (86.7%) 07:20:20 INFO - TinderboxPrint: CPU user
1,205.2 (12.7%) 07:20:20 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78852096; Read time: 0; Write time: 115736 07:20:20 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 245702656; Read time: 0; Write time: 163196 07:20:20 INFO - Running post-run listener: _upload_blobber_files 07:20:20 INFO - Blob upload gear active. 07:20:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:20 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 07:20:20 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:20:20 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:20:21 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:22 INFO - (blobuploader) - INFO - Done attempting. 07:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:23 INFO - (blobuploader) - INFO - Done attempting. 07:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:35 INFO - (blobuploader) - INFO - Done attempting. 07:20:35 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:35 INFO - Return code: 0 07:20:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f852af576270b2ec3fe455edb39f0705fa60c1215ac33e6d1b09637d3a002ac9acdee007c428ad0c4247776a6c1e9ee133f40f0e40da58a21092121d0644490", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ec81e787d5befed15b70285c4fa6ea5bc7382a8ce7ab3df288fbdcc5e406759c5bde74198177643b578fd7f7d03662241a1db2e25325e32792b8783575e692", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e28f4f1d2990d53736373eca3811ab76aab5d47dfe5083ebf29d5a242afd551c6a4cfc3833875688f9318ce663c150ae989cffa9247327ea7b955c395b10f447"} 07:20:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:35 INFO - Contents: 07:20:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f852af576270b2ec3fe455edb39f0705fa60c1215ac33e6d1b09637d3a002ac9acdee007c428ad0c4247776a6c1e9ee133f40f0e40da58a21092121d0644490", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ec81e787d5befed15b70285c4fa6ea5bc7382a8ce7ab3df288fbdcc5e406759c5bde74198177643b578fd7f7d03662241a1db2e25325e32792b8783575e692", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e28f4f1d2990d53736373eca3811ab76aab5d47dfe5083ebf29d5a242afd551c6a4cfc3833875688f9318ce663c150ae989cffa9247327ea7b955c395b10f447"} 07:20:35 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:35 INFO - Copying logs to upload dir... 07:20:35 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1687.124576 ========= master_lag: 1.92 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 9 secs) (at 2016-09-01 07:20:36.286669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 15 secs) (at 2016-09-01 07:20:36.289079) ========= 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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f852af576270b2ec3fe455edb39f0705fa60c1215ac33e6d1b09637d3a002ac9acdee007c428ad0c4247776a6c1e9ee133f40f0e40da58a21092121d0644490", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ec81e787d5befed15b70285c4fa6ea5bc7382a8ce7ab3df288fbdcc5e406759c5bde74198177643b578fd7f7d03662241a1db2e25325e32792b8783575e692", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e28f4f1d2990d53736373eca3811ab76aab5d47dfe5083ebf29d5a242afd551c6a4cfc3833875688f9318ce663c150ae989cffa9247327ea7b955c395b10f447"} build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009882 build_url: 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f852af576270b2ec3fe455edb39f0705fa60c1215ac33e6d1b09637d3a002ac9acdee007c428ad0c4247776a6c1e9ee133f40f0e40da58a21092121d0644490", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7ec81e787d5befed15b70285c4fa6ea5bc7382a8ce7ab3df288fbdcc5e406759c5bde74198177643b578fd7f7d03662241a1db2e25325e32792b8783575e692", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e28f4f1d2990d53736373eca3811ab76aab5d47dfe5083ebf29d5a242afd551c6a4cfc3833875688f9318ce663c150ae989cffa9247327ea7b955c395b10f447"}' ========= master_lag: 15.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 15 secs) (at 2016-09-01 07:20:51.699075) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:51.699403) ========= 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=fa44defc139c4ba8574fe040000001db-1472737642.86613-2011387760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 2.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:54.202145) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-09-01 07:20:54.202462) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-09-01 07:21:01.421459) ========= ========= Total master_lag: 20.03 =========