builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1476370663.75 results: success (0) buildid: 20161013062823 builduid: d58b9deb5b714dfb982d5af662bc827b revision: 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.747115) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.747561) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.747880) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.838288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.838592) ========= 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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 07:57:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.3M=0s 2016-10-13 07:57:44 (41.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.976434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:43.976782) ========= 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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016964 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.032882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.033241) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 --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-beta --rev 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 --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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 07:57:44,180 truncating revision to first 12 chars 2016-10-13 07:57:44,180 Setting DEBUG logging. 2016-10-13 07:57:44,180 attempt 1/10 2016-10-13 07:57:44,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/59e7d8fe1b93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 07:57:44,489 unpacking tar archive at: mozilla-beta-59e7d8fe1b93/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626777 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.677894) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.678571) ========= script_repo_revision: 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.679552) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.680111) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 07:57:44.695841) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-13 07:57:44.696180) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:57:44 INFO - MultiFileLogger online at 20161013 07:57:44 in /builds/slave/test 07:57:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:57:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:57:44 INFO - {'append_to_log': False, 07:57:44 INFO - 'base_work_dir': '/builds/slave/test', 07:57:44 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:57:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:57:44 INFO - 'branch': 'Mozilla-Beta', 07:57:44 INFO - 'buildbot_json_path': 'buildprops.json', 07:57:44 INFO - 'config_files': ('talos/linux_config.py',), 07:57:44 INFO - 'default_actions': ('clobber', 07:57:44 INFO - 'read-buildbot-config', 07:57:44 INFO - 'download-and-extract', 07:57:44 INFO - 'populate-webroot', 07:57:44 INFO - 'create-virtualenv', 07:57:44 INFO - 'install', 07:57:44 INFO - 'run-tests'), 07:57:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:57:44 INFO - 'download_minidump_stackwalk': True, 07:57:44 INFO - 'download_symbols': 'ondemand', 07:57:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:57:44 INFO - 'tooltool.py': '/tools/tooltool.py', 07:57:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:57:44 INFO - '/tools/misc-python/virtualenv.py')}, 07:57:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:57:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:57:44 INFO - 'installer_path': 'installer.exe', 07:57:44 INFO - 'log_level': 'info', 07:57:44 INFO - 'log_name': 'talos', 07:57:44 INFO - 'log_to_console': True, 07:57:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:57:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:57:44 INFO - 'opt_config_files': (), 07:57:44 INFO - 'pip_index': False, 07:57:44 INFO - 'sps_profile': False, 07:57:44 INFO - 'sps_profile_interval': 0, 07:57:44 INFO - 'suite': 'dromaeojs', 07:57:44 INFO - 'system_bits': '32', 07:57:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:57:44 INFO - 'title': 'talos-linux64-ix-047', 07:57:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:57:44 INFO - 'use_talos_json': True, 07:57:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:57:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:57:44 INFO - 'work_dir': 'build'} 07:57:44 INFO - [mozharness: 2016-10-13 14:57:44.938947Z] Running clobber step. 07:57:44 INFO - Running pre-action listener: _resource_record_pre_action 07:57:44 INFO - Running main action method: clobber 07:57:44 INFO - rmtree: /builds/slave/test/build 07:57:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:57:45 INFO - Running post-action listener: _resource_record_post_action 07:57:45 INFO - [mozharness: 2016-10-13 14:57:45.411288Z] Finished clobber step (success) 07:57:45 INFO - [mozharness: 2016-10-13 14:57:45.411710Z] Running read-buildbot-config step. 07:57:45 INFO - Running pre-action listener: _resource_record_pre_action 07:57:45 INFO - Running main action method: read_buildbot_config 07:57:45 INFO - Using buildbot properties: 07:57:45 INFO - { 07:57:45 INFO - "project": "", 07:57:45 INFO - "product": "firefox", 07:57:45 INFO - "script_repo_revision": "production", 07:57:45 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 07:57:45 INFO - "repository": "", 07:57:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 07:57:45 INFO - "buildid": "20161013062823", 07:57:45 INFO - "pgo_build": "True", 07:57:45 INFO - "basedir": "/builds/slave/test", 07:57:45 INFO - "buildnumber": 418, 07:57:45 INFO - "slavename": "talos-linux64-ix-047", 07:57:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:57:45 INFO - "platform": "ubuntu64_hw", 07:57:45 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 07:57:45 INFO - "branch": "mozilla-beta", 07:57:45 INFO - "repo_path": "releases/mozilla-beta", 07:57:45 INFO - "revision": "59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3", 07:57:45 INFO - "stage_platform": "linux64-pgo", 07:57:45 INFO - "builduid": "d58b9deb5b714dfb982d5af662bc827b", 07:57:45 INFO - "slavebuilddir": "test" 07:57:45 INFO - } 07:57:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 07:57:45 INFO - Running post-action listener: _resource_record_post_action 07:57:45 INFO - [mozharness: 2016-10-13 14:57:45.414147Z] Finished read-buildbot-config step (success) 07:57:45 INFO - [mozharness: 2016-10-13 14:57:45.414236Z] Running download-and-extract step. 07:57:45 INFO - Running pre-action listener: _resource_record_pre_action 07:57:45 INFO - Running main action method: download_and_extract 07:57:45 INFO - mkdir: /builds/slave/test/build/tests 07:57:45 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:57:45 INFO - https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:57:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:57:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:57:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:57:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:57:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:57:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:57:45 INFO - retry: Failed, sleeping 30 seconds before retrying 07:58:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 07:58:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:58:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:58:15 INFO - retry: Failed, sleeping 60 seconds before retrying 07:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 07:59:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:59:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:59:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 07:59:15 INFO - Caught exception: HTTP Error 404: Not Found 07:59:15 INFO - Caught exception: HTTP Error 404: Not Found 07:59:15 INFO - Caught exception: HTTP Error 404: Not Found 07:59:15 INFO - trying https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:59:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:59:16 INFO - Downloaded 1327 bytes. 07:59:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 07:59:16 INFO - Using the following test package requirements: 07:59:16 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 07:59:16 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 07:59:16 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 07:59:16 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'jsshell-linux-x86_64.zip'], 07:59:16 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 07:59:16 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 07:59:16 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 07:59:16 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 07:59:16 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 07:59:16 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 07:59:16 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 07:59:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:59: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:59:16 INFO - https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:59:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 07:59:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 07:59:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 07:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:59:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 07:59:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:59:16 INFO - retry: Failed, sleeping 30 seconds before retrying 07:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:59:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 07:59:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:59:46 INFO - retry: Failed, sleeping 60 seconds before retrying 08:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 08:00:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:00:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 08:00:46 INFO - Caught exception: HTTP Error 404: Not Found 08:00:46 INFO - Caught exception: HTTP Error 404: Not Found 08:00:46 INFO - Caught exception: HTTP Error 404: Not Found 08:00:46 INFO - trying https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:47 INFO - Downloaded 21773465 bytes. 08:00:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:00:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:00:49 INFO - Return code: 0 08:00:49 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:00:49 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')]} 08:00:49 INFO - https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:00:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:00:49 INFO - retry: Failed, sleeping 30 seconds before retrying 08:01:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 08:01:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:01:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:01:19 INFO - retry: Failed, sleeping 60 seconds before retrying 08:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 08:02:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:02:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:02:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 08:02:19 INFO - Caught exception: HTTP Error 404: Not Found 08:02:19 INFO - Caught exception: HTTP Error 404: Not Found 08:02:19 INFO - Caught exception: HTTP Error 404: Not Found 08:02:19 INFO - trying https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:02:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:02:20 INFO - Downloaded 21773465 bytes. 08:02:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:02:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:02:21 INFO - Return code: 0 08:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:02:21 INFO - https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:02:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:02:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:02:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:02:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:02:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:02:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:02:21 INFO - retry: Failed, sleeping 30 seconds before retrying 08:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 08:02:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:02:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:02:51 INFO - retry: Failed, sleeping 60 seconds before retrying 08:03:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 08:03:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:03:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:03:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 08:03:51 INFO - Caught exception: HTTP Error 404: Not Found 08:03:51 INFO - Caught exception: HTTP Error 404: Not Found 08:03:51 INFO - Caught exception: HTTP Error 404: Not Found 08:03:51 INFO - trying https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:03:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:03:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:03:53 INFO - Downloaded 14413740 bytes. 08:03:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:03:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:03:53 INFO - Return code: 0 08:03:53 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')]} 08:03:53 INFO - https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:03:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:03:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:03:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:03:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:03:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:03:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:03:53 INFO - retry: Failed, sleeping 30 seconds before retrying 08:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 08:04:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:04:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:04:23 INFO - retry: Failed, sleeping 60 seconds before retrying 08:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 08:05:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:05:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:05:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 08:05:23 INFO - Caught exception: HTTP Error 404: Not Found 08:05:23 INFO - Caught exception: HTTP Error 404: Not Found 08:05:23 INFO - Caught exception: HTTP Error 404: Not Found 08:05:23 INFO - trying https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:05:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:05:25 INFO - Downloaded 56805875 bytes. 08:05:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:05:25 INFO - mkdir: /builds/slave/test/properties 08:05:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:05:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:05:25 INFO - Contents: 08:05:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:05:26 INFO - Running post-action listener: _resource_record_post_action 08:05:26 INFO - Running post-action listener: set_extra_try_arguments 08:05:26 INFO - [mozharness: 2016-10-13 15:05:26.819706Z] Finished download-and-extract step (success) 08:05:26 INFO - [mozharness: 2016-10-13 15:05:26.819939Z] Running populate-webroot step. 08:05:26 INFO - Running pre-action listener: _resource_record_pre_action 08:05:26 INFO - Running main action method: populate_webroot 08:05:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:05:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:05:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:05:26 INFO - '--tppagecycles', 08:05:26 INFO - '7'], 08:05:26 INFO - 'tests': ['tsvgm']}}, 08:05:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:05:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:05:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:05:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:05:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['damp', 'tps']}, 08:05:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'tests': ['damp', 'tps']}, 08:05:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['dromaeo_dom']}, 08:05:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:05:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['basic_compositor_video']}, 08:05:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:05:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['a11yr', 08:05:26 INFO - 'ts_paint', 08:05:26 INFO - 'tpaint', 08:05:26 INFO - 'sessionrestore', 08:05:26 INFO - 'sessionrestore_no_auto_restore', 08:05:26 INFO - 'tabpaint']}, 08:05:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['tsvgx', 08:05:26 INFO - 'tsvgr_opacity', 08:05:26 INFO - 'tart', 08:05:26 INFO - 'tscrollx', 08:05:26 INFO - 'cart']}, 08:05:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:05:26 INFO - 'tsvgr_opacity', 08:05:26 INFO - 'tart', 08:05:26 INFO - 'tscrollx', 08:05:26 INFO - 'cart']}, 08:05:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'talos_options': ['--disable-e10s'], 08:05:26 INFO - 'tests': ['tp5o']}, 08:05:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'tests': ['tp5o']}, 08:05:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'talos_options': ['--disable-e10s', 08:05:26 INFO - '--xperf_path', 08:05:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:26 INFO - 'tests': ['tp5n']}, 08:05:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:26 INFO - 'talos_options': ['--xperf_path', 08:05:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:26 INFO - 'tests': ['tp5n']}}, 08:05:26 INFO - 'talos.zip': {'path': '', 08:05:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:05:26 INFO - Running post-action listener: _resource_record_post_action 08:05:26 INFO - [mozharness: 2016-10-13 15:05:26.840615Z] Finished populate-webroot step (success) 08:05:26 INFO - [mozharness: 2016-10-13 15:05:26.840705Z] Running create-virtualenv step. 08:05:26 INFO - Running pre-action listener: _resource_record_pre_action 08:05:26 INFO - Running main action method: create_virtualenv 08:05:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:05:26 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 08:05:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:05:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:05:26 INFO - Using real prefix '/usr' 08:05:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:05:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:05:29 INFO - Installing pip.................done. 08:05:29 INFO - Return code: 0 08:05:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05: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', 'pip>=1.5'] in /builds/slave/test/build 08:05: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 pip>=1.5 08:05:29 INFO - Using env: {'DISPLAY': ':0', 08:05:29 INFO - 'HOME': '/home/cltbld', 08:05:29 INFO - 'LANG': 'en_US.UTF-8', 08:05:29 INFO - 'LANGUAGE': 'en_US:en', 08:05:29 INFO - 'LOGNAME': 'cltbld', 08:05:29 INFO - 'MAIL': '/var/mail/cltbld', 08:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:29 INFO - 'MOZ_NO_REMOTE': '1', 08:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:29 INFO - 'NO_EM_RESTART': '1', 08:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:29 INFO - 'PWD': '/builds/slave/test', 08:05:29 INFO - 'SHELL': '/bin/bash', 08:05:29 INFO - 'SHLVL': '1', 08:05:29 INFO - 'TERM': 'linux', 08:05:29 INFO - 'TMOUT': '86400', 08:05:29 INFO - 'USER': 'cltbld', 08:05:29 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:29 INFO - '_': '/tools/buildbot/bin/python'} 08:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:29 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 08:05:29 INFO - Cleaning up... 08:05:29 INFO - Return code: 0 08:05:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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', '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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:05: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 psutil>=3.1.1 08:05:29 INFO - Using env: {'DISPLAY': ':0', 08:05:29 INFO - 'HOME': '/home/cltbld', 08:05:29 INFO - 'LANG': 'en_US.UTF-8', 08:05:29 INFO - 'LANGUAGE': 'en_US:en', 08:05:29 INFO - 'LOGNAME': 'cltbld', 08:05:29 INFO - 'MAIL': '/var/mail/cltbld', 08:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:29 INFO - 'MOZ_NO_REMOTE': '1', 08:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:29 INFO - 'NO_EM_RESTART': '1', 08:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:29 INFO - 'PWD': '/builds/slave/test', 08:05:29 INFO - 'SHELL': '/bin/bash', 08:05:29 INFO - 'SHLVL': '1', 08:05:29 INFO - 'TERM': 'linux', 08:05:29 INFO - 'TMOUT': '86400', 08:05:29 INFO - 'USER': 'cltbld', 08:05:29 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:29 INFO - '_': '/tools/buildbot/bin/python'} 08:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:29 INFO - Downloading/unpacking psutil>=3.1.1 08:05:29 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 08:05:29 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 08:05:29 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 08:05:29 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 08:05:29 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 08:05:29 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 08:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:05:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:31 INFO - Installing collected packages: psutil 08:05:31 INFO - Running setup.py install for psutil 08:05:31 INFO - building 'psutil._psutil_linux' extension 08:05:31 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 08:05:32 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 08:05:32 INFO - building 'psutil._psutil_posix' extension 08:05:32 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 08:05:32 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 08:05:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:32 INFO - Successfully installed psutil 08:05:32 INFO - Cleaning up... 08:05:32 INFO - Return code: 0 08:05:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:05:32 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')]} 08:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:32 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')]} 08:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:32 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:32 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 08:05:32 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 08:05:32 INFO - Using env: {'DISPLAY': ':0', 08:05:32 INFO - 'HOME': '/home/cltbld', 08:05:32 INFO - 'LANG': 'en_US.UTF-8', 08:05:32 INFO - 'LANGUAGE': 'en_US:en', 08:05:32 INFO - 'LOGNAME': 'cltbld', 08:05:32 INFO - 'MAIL': '/var/mail/cltbld', 08:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:32 INFO - 'MOZ_NO_REMOTE': '1', 08:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:32 INFO - 'NO_EM_RESTART': '1', 08:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:32 INFO - 'PWD': '/builds/slave/test', 08:05:32 INFO - 'SHELL': '/bin/bash', 08:05:32 INFO - 'SHLVL': '1', 08:05:32 INFO - 'TERM': 'linux', 08:05:32 INFO - 'TMOUT': '86400', 08:05:32 INFO - 'USER': 'cltbld', 08:05:32 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:32 INFO - '_': '/tools/buildbot/bin/python'} 08:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:05:32 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 08:05:32 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 08:05:32 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 08:05:32 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 08:05:32 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 08:05:32 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 08:05:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:05:34 INFO - Installing collected packages: mozsystemmonitor 08:05:34 INFO - Running setup.py install for mozsystemmonitor 08:05:34 INFO - Successfully installed mozsystemmonitor 08:05:34 INFO - Cleaning up... 08:05:34 INFO - Return code: 0 08:05:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:05:34 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')]} 08:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 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')]} 08:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:34 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 08:05:34 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 08:05:34 INFO - Using env: {'DISPLAY': ':0', 08:05:34 INFO - 'HOME': '/home/cltbld', 08:05:34 INFO - 'LANG': 'en_US.UTF-8', 08:05:34 INFO - 'LANGUAGE': 'en_US:en', 08:05:34 INFO - 'LOGNAME': 'cltbld', 08:05:34 INFO - 'MAIL': '/var/mail/cltbld', 08:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:34 INFO - 'MOZ_NO_REMOTE': '1', 08:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:34 INFO - 'NO_EM_RESTART': '1', 08:05:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:34 INFO - 'PWD': '/builds/slave/test', 08:05:34 INFO - 'SHELL': '/bin/bash', 08:05:34 INFO - 'SHLVL': '1', 08:05:34 INFO - 'TERM': 'linux', 08:05:34 INFO - 'TMOUT': '86400', 08:05:34 INFO - 'USER': 'cltbld', 08:05:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:34 INFO - '_': '/tools/buildbot/bin/python'} 08:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05:36 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 08:05:36 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 08:05:36 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 08:05:36 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 08:05:36 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 08:05:36 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 08:05:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:37 INFO - warning: no files found matching '*.txt' 08:05:37 INFO - no previously-included directories found matching 'build' 08:05:37 INFO - no previously-included directories found matching 'dist' 08:05:37 INFO - no previously-included directories found matching '.git*' 08:05:37 INFO - Installing collected packages: jsonschema, functools32 08:05:37 INFO - Running setup.py install for functools32 08:05:37 INFO - warning: no files found matching '*.txt' 08:05:37 INFO - no previously-included directories found matching 'build' 08:05:37 INFO - no previously-included directories found matching 'dist' 08:05:37 INFO - no previously-included directories found matching '.git*' 08:05:37 INFO - Successfully installed jsonschema functools32 08:05:37 INFO - Cleaning up... 08:05:37 INFO - Return code: 0 08:05:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:05:37 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')]} 08:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:37 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')]} 08:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:37 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:37 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 08:05:37 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 08:05:37 INFO - Using env: {'DISPLAY': ':0', 08:05:37 INFO - 'HOME': '/home/cltbld', 08:05:37 INFO - 'LANG': 'en_US.UTF-8', 08:05:37 INFO - 'LANGUAGE': 'en_US:en', 08:05:37 INFO - 'LOGNAME': 'cltbld', 08:05:37 INFO - 'MAIL': '/var/mail/cltbld', 08:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:37 INFO - 'MOZ_NO_REMOTE': '1', 08:05:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:37 INFO - 'NO_EM_RESTART': '1', 08:05:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:37 INFO - 'PWD': '/builds/slave/test', 08:05:37 INFO - 'SHELL': '/bin/bash', 08:05:37 INFO - 'SHLVL': '1', 08:05:37 INFO - 'TERM': 'linux', 08:05:37 INFO - 'TMOUT': '86400', 08:05:37 INFO - 'USER': 'cltbld', 08:05:37 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:37 INFO - '_': '/tools/buildbot/bin/python'} 08:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:05:37 INFO - Cleaning up... 08:05:37 INFO - Return code: 0 08:05:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:37 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')]} 08:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:37 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')]} 08:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:37 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:37 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 08:05:37 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 08:05:37 INFO - Using env: {'DISPLAY': ':0', 08:05:37 INFO - 'HOME': '/home/cltbld', 08:05:37 INFO - 'LANG': 'en_US.UTF-8', 08:05:37 INFO - 'LANGUAGE': 'en_US:en', 08:05:37 INFO - 'LOGNAME': 'cltbld', 08:05:37 INFO - 'MAIL': '/var/mail/cltbld', 08:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:37 INFO - 'MOZ_NO_REMOTE': '1', 08:05:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:37 INFO - 'NO_EM_RESTART': '1', 08:05:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:37 INFO - 'PWD': '/builds/slave/test', 08:05:37 INFO - 'SHELL': '/bin/bash', 08:05:37 INFO - 'SHLVL': '1', 08:05:37 INFO - 'TERM': 'linux', 08:05:37 INFO - 'TMOUT': '86400', 08:05:37 INFO - 'USER': 'cltbld', 08:05:37 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:37 INFO - '_': '/tools/buildbot/bin/python'} 08:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:05:37 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 08:05:37 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 08:05:37 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 08:05:37 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 08:05:37 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 08:05:37 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 08:05:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:39 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 08:05:39 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 08:05:39 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 08:05:39 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 08:05:39 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 08:05:39 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 08:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:40 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 08:05:40 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 08:05:40 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 08:05:40 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 08:05:40 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 08:05:40 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 08:05:40 INFO - Downloading docopt-0.6.1.tar.gz 08:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:40 INFO - Running setup.py install for blobuploader 08:05:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:40 INFO - Running setup.py install for requests 08:05:40 INFO - Running setup.py install for docopt 08:05:41 INFO - Successfully installed blobuploader requests docopt 08:05:41 INFO - Cleaning up... 08:05:41 INFO - Return code: 0 08:05:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:41 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')]} 08:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:41 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')]} 08:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:41 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:41 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 08:05:41 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 08:05:41 INFO - Using env: {'DISPLAY': ':0', 08:05:41 INFO - 'HOME': '/home/cltbld', 08:05:41 INFO - 'LANG': 'en_US.UTF-8', 08:05:41 INFO - 'LANGUAGE': 'en_US:en', 08:05:41 INFO - 'LOGNAME': 'cltbld', 08:05:41 INFO - 'MAIL': '/var/mail/cltbld', 08:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:41 INFO - 'MOZ_NO_REMOTE': '1', 08:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:41 INFO - 'NO_EM_RESTART': '1', 08:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:41 INFO - 'PWD': '/builds/slave/test', 08:05:41 INFO - 'SHELL': '/bin/bash', 08:05:41 INFO - 'SHLVL': '1', 08:05:41 INFO - 'TERM': 'linux', 08:05:41 INFO - 'TMOUT': '86400', 08:05:41 INFO - 'USER': 'cltbld', 08:05:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:41 INFO - '_': '/tools/buildbot/bin/python'} 08:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:41 INFO - Running setup.py (path:/tmp/pip-yF35cS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:41 INFO - Running setup.py (path:/tmp/pip-2LDYGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:41 INFO - Running setup.py (path:/tmp/pip-ojLkHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:41 INFO - Running setup.py (path:/tmp/pip-VwdbvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:41 INFO - Running setup.py (path:/tmp/pip-p_9wRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:41 INFO - Running setup.py (path:/tmp/pip-OjGn5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:41 INFO - Running setup.py (path:/tmp/pip-wS80gR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:41 INFO - Running setup.py (path:/tmp/pip-SAHkvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:41 INFO - Running setup.py (path:/tmp/pip-7lR6rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:41 INFO - Running setup.py (path:/tmp/pip-f6Ehez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:42 INFO - Running setup.py (path:/tmp/pip-L5yo1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:42 INFO - Running setup.py (path:/tmp/pip-pxNwJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:42 INFO - Running setup.py (path:/tmp/pip-nu7b3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:42 INFO - Running setup.py (path:/tmp/pip-oZloLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:42 INFO - Running setup.py (path:/tmp/pip-j33GdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:42 INFO - Running setup.py (path:/tmp/pip-Uah8Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:42 INFO - Running setup.py (path:/tmp/pip-_b4Wqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:42 INFO - Running setup.py install for manifestparser 08:05:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:42 INFO - Running setup.py install for mozcrash 08:05:42 INFO - Running setup.py install for mozdebug 08:05:42 INFO - Running setup.py install for mozdevice 08:05:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:42 INFO - Running setup.py install for mozfile 08:05:43 INFO - Running setup.py install for mozhttpd 08:05:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozinfo 08:05:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozInstall 08:05:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozleak 08:05:43 INFO - Running setup.py install for mozlog 08:05:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for moznetwork 08:05:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozprocess 08:05:43 INFO - Running setup.py install for mozprofile 08:05:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozrunner 08:05:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:43 INFO - Running setup.py install for mozscreenshot 08:05:44 INFO - Running setup.py install for moztest 08:05:44 INFO - Running setup.py install for mozversion 08:05:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:44 INFO - Cleaning up... 08:05:44 INFO - Return code: 0 08:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:44 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')]} 08:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:44 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')]} 08:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:44 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:44 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 08:05:44 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 08:05:44 INFO - Using env: {'DISPLAY': ':0', 08:05:44 INFO - 'HOME': '/home/cltbld', 08:05:44 INFO - 'LANG': 'en_US.UTF-8', 08:05:44 INFO - 'LANGUAGE': 'en_US:en', 08:05:44 INFO - 'LOGNAME': 'cltbld', 08:05:44 INFO - 'MAIL': '/var/mail/cltbld', 08:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:44 INFO - 'MOZ_NO_REMOTE': '1', 08:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:44 INFO - 'NO_EM_RESTART': '1', 08:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:44 INFO - 'PWD': '/builds/slave/test', 08:05:44 INFO - 'SHELL': '/bin/bash', 08:05:44 INFO - 'SHLVL': '1', 08:05:44 INFO - 'TERM': 'linux', 08:05:44 INFO - 'TMOUT': '86400', 08:05:44 INFO - 'USER': 'cltbld', 08:05:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:44 INFO - '_': '/tools/buildbot/bin/python'} 08:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:44 INFO - Running setup.py (path:/tmp/pip-aq2nCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:44 INFO - Running setup.py (path:/tmp/pip-Z5WIDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:44 INFO - Running setup.py (path:/tmp/pip-cpc7_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:44 INFO - Running setup.py (path:/tmp/pip-aZQDJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:44 INFO - Running setup.py (path:/tmp/pip-n7o7wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:44 INFO - Running setup.py (path:/tmp/pip-SA36mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:44 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)) 08:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:44 INFO - Running setup.py (path:/tmp/pip-dnrAk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:45 INFO - Running setup.py (path:/tmp/pip-sajy28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:45 INFO - Running setup.py (path:/tmp/pip-JB8XmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:45 INFO - Running setup.py (path:/tmp/pip-K45BSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:45 INFO - Running setup.py (path:/tmp/pip-uj74Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:45 INFO - Running setup.py (path:/tmp/pip-Y1ugbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:45 INFO - Running setup.py (path:/tmp/pip-pALvGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:45 INFO - Running setup.py (path:/tmp/pip-_xOgZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:45 INFO - Running setup.py (path:/tmp/pip-PFLcvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:45 INFO - Running setup.py (path:/tmp/pip-zcmIMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:45 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)) 08:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:45 INFO - Running setup.py (path:/tmp/pip-8jAxH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:45 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)) 08:05:45 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)) 08:05:45 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)) 08:05:45 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)) 08:05:45 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)) 08:05:45 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)) 08:05:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05:45 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 08:05:45 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 08:05:45 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 08:05:45 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 08:05:45 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 08:05:45 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 08:05:47 INFO - Downloading blessings-1.6.tar.gz 08:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:47 INFO - Installing collected packages: blessings 08:05:47 INFO - Running setup.py install for blessings 08:05:47 INFO - Successfully installed blessings 08:05:47 INFO - Cleaning up... 08:05:47 INFO - Return code: 0 08:05:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:48 INFO - Reading from file tmpfile_stdout 08:05:48 INFO - Current package versions: 08:05:48 INFO - argparse == 1.2.1 08:05:48 INFO - blessings == 1.6 08:05:48 INFO - blobuploader == 1.2.4 08:05:48 INFO - docopt == 0.6.1 08:05:48 INFO - functools32 == 3.2.3-2 08:05:48 INFO - jsonschema == 2.5.1 08:05:48 INFO - manifestparser == 1.1 08:05:48 INFO - mozInstall == 1.12 08:05:48 INFO - mozcrash == 0.17 08:05:48 INFO - mozdebug == 0.1 08:05:48 INFO - mozdevice == 0.48 08:05:48 INFO - mozfile == 1.2 08:05:48 INFO - mozhttpd == 0.7 08:05:48 INFO - mozinfo == 0.9 08:05:48 INFO - mozleak == 0.1 08:05:48 INFO - mozlog == 3.3 08:05:48 INFO - moznetwork == 0.27 08:05:48 INFO - mozprocess == 0.23 08:05:48 INFO - mozprofile == 0.28 08:05:48 INFO - mozrunner == 6.12 08:05:48 INFO - mozscreenshot == 0.1 08:05:48 INFO - mozsystemmonitor == 0.3 08:05:48 INFO - moztest == 0.7 08:05:48 INFO - mozversion == 1.4 08:05:48 INFO - psutil == 3.1.1 08:05:48 INFO - requests == 1.2.3 08:05:48 INFO - wsgiref == 0.1.2 08:05:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:48 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')]} 08:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:48 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')]} 08:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:48 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:48 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 08:05:48 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 08:05:48 INFO - Using env: {'DISPLAY': ':0', 08:05:48 INFO - 'HOME': '/home/cltbld', 08:05:48 INFO - 'LANG': 'en_US.UTF-8', 08:05:48 INFO - 'LANGUAGE': 'en_US:en', 08:05:48 INFO - 'LOGNAME': 'cltbld', 08:05:48 INFO - 'MAIL': '/var/mail/cltbld', 08:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:48 INFO - 'MOZ_NO_REMOTE': '1', 08:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:48 INFO - 'NO_EM_RESTART': '1', 08:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:48 INFO - 'PWD': '/builds/slave/test', 08:05:48 INFO - 'SHELL': '/bin/bash', 08:05:48 INFO - 'SHLVL': '1', 08:05:48 INFO - 'TERM': 'linux', 08:05:48 INFO - 'TMOUT': '86400', 08:05:48 INFO - 'USER': 'cltbld', 08:05:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:48 INFO - '_': '/tools/buildbot/bin/python'} 08:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 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)) 08:05:48 INFO - Cleaning up... 08:05:48 INFO - Return code: 0 08:05:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:05:48 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')]} 08:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:48 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')]} 08:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:48 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 0x1d53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2014180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, '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': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', '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 08:05:48 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 08:05:48 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 08:05:48 INFO - Using env: {'DISPLAY': ':0', 08:05:48 INFO - 'HOME': '/home/cltbld', 08:05:48 INFO - 'LANG': 'en_US.UTF-8', 08:05:48 INFO - 'LANGUAGE': 'en_US:en', 08:05:48 INFO - 'LOGNAME': 'cltbld', 08:05:48 INFO - 'MAIL': '/var/mail/cltbld', 08:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:48 INFO - 'MOZ_NO_REMOTE': '1', 08:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:48 INFO - 'NO_EM_RESTART': '1', 08:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:48 INFO - 'PWD': '/builds/slave/test', 08:05:48 INFO - 'SHELL': '/bin/bash', 08:05:48 INFO - 'SHLVL': '1', 08:05:48 INFO - 'TERM': 'linux', 08:05:48 INFO - 'TMOUT': '86400', 08:05:48 INFO - 'USER': 'cltbld', 08:05:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:48 INFO - '_': '/tools/buildbot/bin/python'} 08:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:05:48 INFO - Cleaning up... 08:05:48 INFO - Return code: 0 08:05:48 INFO - Running post-action listener: _resource_record_post_action 08:05:48 INFO - Running post-action listener: _start_resource_monitoring 08:05:48 INFO - Starting resource monitoring. 08:05:48 INFO - [mozharness: 2016-10-13 15:05:48.626759Z] Finished create-virtualenv step (success) 08:05:48 INFO - [mozharness: 2016-10-13 15:05:48.627233Z] Running install step. 08:05:48 INFO - Running pre-action listener: _resource_record_pre_action 08:05:48 INFO - Running main action method: install 08:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:48 INFO - Reading from file tmpfile_stdout 08:05:48 INFO - Detecting whether we're running mozinstall >=1.0... 08:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:05:48 INFO - Reading from file tmpfile_stdout 08:05:48 INFO - Output received: 08:05:48 INFO - Usage: mozinstall [options] installer 08:05:48 INFO - Options: 08:05:48 INFO - -h, --help show this help message and exit 08:05:48 INFO - -d DEST, --destination=DEST 08:05:48 INFO - Directory to install application into. [default: 08:05:48 INFO - "/builds/slave/test"] 08:05:48 INFO - --app=APP Application being installed. [default: firefox] 08:05:48 INFO - mkdir: /builds/slave/test/build/application 08:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:06:02 INFO - Reading from file tmpfile_stdout 08:06:02 INFO - Output received: 08:06:02 INFO - /builds/slave/test/build/application/firefox/firefox 08:06:02 INFO - Running post-action listener: _resource_record_post_action 08:06:02 INFO - [mozharness: 2016-10-13 15:06:02.419197Z] Finished install step (success) 08:06:02 INFO - [mozharness: 2016-10-13 15:06:02.419587Z] Running run-tests step. 08:06:02 INFO - Running pre-action listener: _resource_record_pre_action 08:06:02 INFO - Running main action method: run_tests 08:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:06:02 INFO - Python 2.7.3 08:06:02 INFO - Return code: 0 08:06:02 INFO - grabbing minidump binary from tooltool 08:06:02 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')]} 08:06:02 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 0x20123b0>, '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 0x20132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2013740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:06:02 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 08:06:02 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 08:06:02 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 08:06:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:06:02 INFO - Return code: 0 08:06:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:06:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:06:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:06:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:06:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:06:02 INFO - Using env: {'DISPLAY': ':0', 08:06:02 INFO - 'HOME': '/home/cltbld', 08:06:02 INFO - 'LANG': 'en_US.UTF-8', 08:06:02 INFO - 'LANGUAGE': 'en_US:en', 08:06:02 INFO - 'LOGNAME': 'cltbld', 08:06:02 INFO - 'MAIL': '/var/mail/cltbld', 08:06:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:06:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:02 INFO - 'MOZ_NO_REMOTE': '1', 08:06:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:06:02 INFO - 'NO_EM_RESTART': '1', 08:06:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:02 INFO - 'PWD': '/builds/slave/test', 08:06:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:06:02 INFO - 'SHELL': '/bin/bash', 08:06:02 INFO - 'SHLVL': '1', 08:06:02 INFO - 'TERM': 'linux', 08:06:02 INFO - 'TMOUT': '86400', 08:06:02 INFO - 'USER': 'cltbld', 08:06:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750', 08:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:02 INFO - '_': '/tools/buildbot/bin/python'} 08:06:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:06:02 INFO - mozversion application_buildid: 20161013062823 08:06:02 INFO - mozversion application_changeset: 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 08:06:02 INFO - mozversion application_display_name: Firefox 08:06:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:06:02 INFO - mozversion application_name: Firefox 08:06:02 INFO - mozversion application_remotingname: firefox 08:06:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:06:02 INFO - mozversion application_vendor: Mozilla 08:06:02 INFO - mozversion application_version: 50.0 08:06:02 INFO - mozversion platform_buildid: 20161013062823 08:06:02 INFO - mozversion platform_changeset: 59e7d8fe1b93fbb78f0e0cc03bdfb58e0909bce3 08:06:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:06:02 INFO - mozversion platform_version: 50.0 08:06:02 INFO - using testdate: 1476371162 08:06:02 INFO - actual date: 1476371162 08:06:02 INFO - starting webserver on 'localhost:42466' 08:06:02 INFO - SUITE-START | Running 2 tests 08:06:02 INFO - TEST-START | dromaeo_css 08:06:02 INFO - operating with platform_type : linux_ 08:06:02 INFO - Initialising browser for dromaeo_css test... 08:06:02 INFO - TEST-INFO | started process 24056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXH2aHw/profile http://localhost:42466/getInfo.html) 08:06:06 INFO - PROCESS | 24056 | __metrics Screen width/height:1600/1200 08:06:06 INFO - PROCESS | 24056 | colorDepth:24 08:06:06 INFO - PROCESS | 24056 | Browser inner width/height: 1024/697 08:06:06 INFO - PROCESS | 24056 | __metrics 08:06:07 INFO - PROCESS | 24056 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:06:07 INFO - PROCESS | 24056 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:06:08 INFO - PROCESS | 24056 | console.error: PushService: 08:06:08 INFO - PROCESS | 24056 | stateChangeProcessEnqueue: Error transitioning state 08:06:08 INFO - PROCESS | 24056 | UnknownError 08:06:08 INFO - TEST-INFO | 24056: exit 0 08:06:08 INFO - Browser initialized. 08:06:08 INFO - Running cycle 1/1 for dromaeo_css test... 08:06:08 INFO - TEST-INFO | started process 24142 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXH2aHw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:20 INFO - PROCESS | 24142 | RSS: Main: 144310272 08:06:20 INFO - PROCESS | 24142 | 08:07:12 INFO - PROCESS | 24142 | 1476371232227 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] 08:09:19 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42466/tests/dromaeo/cssquery-ext.html) 08:09:21 INFO - PROCESS | 24142 | RSS: Main: 270614528 08:09:21 INFO - PROCESS | 24142 | 08:12:23 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-ext.html (next: http://localhost:42466/tests/dromaeo/cssquery-jquery.html) 08:12:23 INFO - PROCESS | 24142 | RSS: Main: 228163584 08:12:23 INFO - PROCESS | 24142 | 08:15:25 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42466/tests/dromaeo/cssquery-mootools.html) 08:15:26 INFO - PROCESS | 24142 | RSS: Main: 235421696 08:15:26 INFO - PROCESS | 24142 | 08:18:28 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42466/tests/dromaeo/cssquery-prototype.html) 08:18:28 INFO - PROCESS | 24142 | RSS: Main: 176816128 08:18:28 INFO - PROCESS | 24142 | 08:21:31 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42466/tests/dromaeo/cssquery-yui.html) 08:21:31 INFO - PROCESS | 24142 | RSS: Main: 176902144 08:21:31 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | Cycle 1(1): loaded http://localhost:42466/tests/dromaeo/cssquery-yui.html (next: http://localhost:42466/tests/dromaeo/cssquery-dojo.html) 08:24:34 INFO - PROCESS | 24142 | RSS: Main: 265011200 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | __start_tp_report 08:24:34 INFO - PROCESS | 24142 | _x_x_mozilla_page_load 08:24:34 INFO - PROCESS | 24142 | _x_x_mozilla_page_load_details 08:24:34 INFO - PROCESS | 24142 | |i|pagename|runs| 08:24:34 INFO - PROCESS | 24142 | |0;dojo.html;3201.1676963989357;3203.695648913353;3239.433099207638;3248.837558122096;3271.2312606537475;5541.833744987652;5636.577256705748;5638.746256418461;5648.717564121798;5656.915146272799;5603.691796951176;5621.662700237978;5688.772449102042;5699.829005129853;5712.857178570538;5092.999999999991;5122.871928201806;5147.408048074463;5178;5183.222516622518;5022.773975171126;5184.740762961856;5200.453952335007;5207.192885102815;5231.816886408977;4987.476314986929;5011.699298042111;5039.546440820325;5043.722595257259;5074.264231686403;5039.72281524516;5056.7977280908535;5085.567726743231;5161.61287903405;5189.1697328427645;4999.250112483121;5070.72111033893;5117.590592752571;5122.052420302219;5138.7687554059785;5386.461353864622;5423.972880135613;5441.319835020622;5456.945430545684;5505.614606977513;5857.384974577651;5861.794837180721;5920.171176035358;5963.582549221556;5969.104634304846;5884.705764711747;5981.342052374235;5994.730237139295;5995.640261584319;5997.760089596454;5545.1682247663175;5558.583106267046;5598.58010649203;5636.04187288162;5664.773409063674;6167.190546293764;6193.411625895533;6199.814005579839;6203.31763506014;6203.8138855834395;5759.625624334405;5769.624974376736;5946.554008449383;5974.761009559656;5997.160397544348;5903.970480147572;5958.255218097738;5962.314333851576;5989.490893274121;5994.190784244159;5927.614705044159;5931.110333450017;5931.73307201177;5933.258342707161;5938.762449501972;5798.246227990334;5800.767969281182;5945.256842894638;5946.375630558729;5950.107483877453;4520.299353600197;4539.909201815945;4548.613367863769;4563.817447302071;4570.520095390003;4032.1330913853662;4034.6973976951845;4045;4054.614811592894;4075.9796201018803;5608.355039170548;5612.214289999403;5666.575006874501;5704.515116215168;5723.971380143073;6006.519478441714;6013.128096426081;6023.45788879003;6026.849328766816;6033.336333003366;5717.799877004285;5722.170285308607;5727.684977326204;5732.512736417451;5732.713364331767;5097.388313402415;5097.6941383517105;5170.121079416508;5175.275461435402;5180.507851754077;6124.899720462962;6224.253089629183;6236.532260080512;6239.937600624026;6246.625202487865;5925.555583331354;5956.553258505716;5985.521158307238;5996.94175893688;6036.245469316335;5900.291964964232;6036.245469316335;6068.271807383142;6159.476444502088;6172;5828.154917537018;5959.28488581373;5967.343592204941;5967.433093856077;5993.790217342372;6057.9701450754355;6060.6969651516365;6060.878782424239;6063.151158837678;6064.363241859542;5782.681952492653;5809.970950145223;5904.380040095728;5904.645721256738;5917.349091600006;5801.593888427769;5884.852878678067;5886.352501224947;5909.6158749681135;5923.348431672599;5869.207656966255;5869.706514674163;5870.618409803349;5873.882522349614;5875.383084776037;5750.022496175577;5802.100674395307;5886.764529418775;5892.292924849045;5945.227120474484;5738.454846789548;5789;5789.189513468202;5798.56510761703;5838.416158384128 08:24:34 INFO - PROCESS | 24142 | |1;ext.html;592.8814237152568;627.0562802981513;653.8169312592473;660.1780782925256;661.8477750117476;34711.47932781007;37432;37542;37570.812145939264;47345.7632711836;44263.336049959275;47555.6711030228;47652.52347476524;47654;48054.51945480544;9205.401648892834;9335.906640933572;9389.953050234739;9390.107939745712;9391.85912211314;8544.658213671446;9220.492872892022;9323.906760932405;10974.176936729737;11216.551337946472;11450.770984580304;11526.65420037396;11529;11530.654080377602;11545.595904143356;11302.321860688344;11358.375289359083;11360.091192704605;11364;11368.033717134054;11141.888581114166;11195.384253866034;11215.271007384576;11219;11222.382768947704;44335;44978.325325120146;45004.09991800178;45033.77483112564;45057;3872.477215575889;3921.941170882439;3954.110325176841;4297.935530967038;4614.676972611918;33887.661123388694;35607.64392356069;35716.28567428663;35860.82069589661;35988.460173097425;4586.243269860469;4598.080383923195;4608.677392582521;4620.676552641316;4702.670813043088;383888;386939;389012;390037;391159.99999999715;69688;70176.29823701698;70642.64678676573;70711.64644176746;70863.64568177126;40617.796911015845;41129.383059254134;41245;72345;72742;16987.74518382225;17138.40015599448;17143.057131857866;17147.22837472292;17157.313707451813;12800.80798788019;12865;12867.42096605655;12873.549425770054;12884.871151288555;19027.401984834556;21648;22218.333449996528;22927.426814329774;23005.65491517595;8151.755447336589;8215.794605134919;8277.793055173668;8336.916630833735;8367.330613550901;1035.570238351076;1100.5322737836389;1160.4313886195703;1190.898773604236;1192.582596091361;35293.64706352955;35630.821845890605;35899.66348122158;36020;36037.27925441477;1122.3770807201918;1133.4276190523733;1138.6527109231763;1151.8272259161026;1161.1639619474133;17989.820101799076;18359.90820045891;18417.631647366976;18443.538911527052;18525.81474185241;560282;566278;567638;637827;991958;66845.66577167083;67443.3255667437;67448.32551674519;67497.66251168713;67512;1332.460353556811;1421.5024741340449;1457.3296283709612;1457.7521821290406;1501.8347981722002;31094.844525777225;35684.107897301736;35811;36125.81937090298;36179.457308140925;56376;57062.714686427964;57111.14333284921;57195.14207286811;57232.42767572271;477640;478215;479956;483928;486402;31461.528077078405;32169.195770105936;32211.194720131247;32392;32627.673723262462;53234.46765532295;53259.46740532545;53404.73297633487;53969;54083;44656.33015504835;46775;47144.528554714016;47284.52715472801;47852.5214747862;45466.545334547554;46263;46409;46728.532714672416;47540;44147.558524414344;44262.114757704025;44469;44723;45535.54464455313;38227.617723822405;39992;40034.59965400309;40377.79811100926;41084.79457602693;29194.70805292005;29738.553921691626;30251;30438.84780576083;30998.690013099582 08:24:34 INFO - PROCESS | 24142 | |2;jquery.html;388.22161566060043;392.16077593948955;411.6542104632109;413.8592878421337;432.3104648086301;14829.55511334659;18600.25598976041;20537.281195158153;20592.279270225525;20655.586888262227;15695.45065922693;18593;18681.532961675966;18734.625307493843;19435.708464373045;6078.452939235468;6230.25236971564;6233.50131989442;6236.750529978795;6266.905996410058;6135.171751813515;6158.56890017699;6180.84547886302;6183.628982261056;6194.070889366609;5983.371745966654;5986.161937328778;5993.400659933994;6030.427109424598;6134.631922084689;6033.879322413527;6097.3292937776805;6107.297660769002;6135.202423684915;6136.815895523119;5503.284573005483;5518.978988887069;5523.392426833086;5528.170774383834;5536.584756143306;16280;21611.567768644538;22323.776762232334;22477.438064048365;23984;2480.987595062013;2488.340589743719;2492.7382624824313;2629.3032346428213;2946.7347938685407;10753.30103543275;10976.176786740947;11093.057090147347;11136.443177841156;11226.158038147172;1756.6135450200936;1787.2046939112101;1826.4794533558006;1849.3619701203063;1878.3331917169344;84761;87034.08346624418;87494.56252718759;87830.56084719536;88122.11877881203;11418.429078546205;11593.304401735923;11760.117991150699;11941.70145746346;11943.522259109539;16882.493525194262;16969.490915272563;16989.66020679604;16999.320027198773;17013.404530841362;7765.689372425039;8022.83954320922;8137.633806478722;8183;8402.201790829984;7692.0384951881015;7763.0684317882215;7869.370450363957;7997.720079797179;8077.474964127313;4683.742394168354;4812.326274321597;4968.155413579628;5065.3161823153405;5086.415062267813;2067.6071546406133;2128;2130.552583957355;2150.172183709283;2150.860194087379;930.8603709443502;997.2470784557613;1027.984580231297;1052.2634156090767;1082.1775450657399;18934;20193;20796.272130475663;21151.153953841673;21690.566188676137;1039.184240371305;1053.5891002509027;1066.5893630951985;1072.485207100596;1082.5615625671458;10939.28894621847;11850.348230847387;11936.940315298369;11970.58102966392;12027.518899244107;499723;500867;501102;519643;520134;95288.39721637101;96549;97048;99603;100050.4997475008;1012.7366884609908;1052.8210204265292;1060.2313322841032;1062.6174577152374;1072.9570817167225;18016.729749053513;18502.537436563656;18854.24583016664;18950.13644257008;19077.71383429222;44154.337684935395;44388;45415.54584454245;46326.30510542412;46606.76696616495;63821.22326169961;64530.67734661297;64681;65798;66547.00179497215;8792.824143517222;8867.201951824367;9189.632414703605;9272.58273377686;9386.67146649864;8509.44688595264;10217.540210690388;13237.735245295233;14011.859881401464;14355.210463424195;11945.701357466132;12044.638660840188;12502.374881255719;12547.184433011826;12769.55306564303;11996.64010079699;12132;12379.319137447526;12414.392977474832;12727.618171454871;12391.814122788346;12422.440990155645;12497.500099995897;12620.495180192689;12667.366631668565;8952.865707014531;9061.954690226507;9080.954595226982;9256.768580785534;9461.621535138518;10199;11546.82679759821;11605.419729013684;11811;12132.150749447454 08:24:34 INFO - PROCESS | 24142 | |3;mootools.html;1544.3359355477144;1586.4368149306995;1733.2633630706955;1804.9278028878846;1857.9256829726812;8123.634436450359;8966.327525435601;8969.955150224248;9464.905350946497;9540.90459095409;8932.687355942544;9347.579358928848;9354.579043943022;9363.876334839812;9369.48467834269;2399;2436.8050555955483;2444.4744379958306;2444.706635203778;2446.6207737800582;2422.9636555451684;2426.34488688055;2474.2329877737957;2475.207933461293;2478.950420991579;2389.247387073075;2407.9036838526526;2421.83047186697;2447.106806015809;2451.007342026482;2374.2046414451006;2379.6311571706415;2386.486905315357;2391.5097405031925;2405.711314642254;2351.1770880191966;2351.9647205291935;2365.3731761083327;2366.420227044368;2372.79831214347;9351.672691455766;9370.718878433658;9447.763805904839;9455.952720236355;9474.38416502925;5473.0969390050595;5645.096784514458;5646.802361917313;5650.491455768959;5659.51894089003;3661.9267614647556;3674.173311005029;3690.447232164577;3695.519582454264;3753.493278407407;1336.6057013181037;1338.2372047932681;1343.0799902067115;1374.6838227207797;1377.2838124175373;12975.935120324433;13043.282619455926;13061.804072938914;13070.934645326808;13102.08285420021;4875.195592727231;4898.2652602109965;4937.432195297515;4967.552920237196;5112.3865136183895;11080.889191107986;11118.61084862026;11119.332840029625;11125.888741112647;11136.721581960515;5570.164475328733;5570.749316280696;5572.665640061528;5575.303087114111;5575.386707462176;4505.324201369755;4586.194739481705;4622.976885115553;4960.181570040902;5155.381354237516;4320.200762858881;4332.848350307724;4334.28484300087;4428.822847086145;4443.0669559392245;1322.4842311498523;1322.8544860065385;1343.7446885091802;1362.1486570893192;1387.458891032498;1055.303499690216;1069.6256310291492;1080.8756992945755;1081.6538707613645;1096.17786660005;6169.2288463942;6247.75008999644;6338.01760727088;6347.460465860361;6521.152250207538;496.38448324078604;506.21031191341626;506.8150125204348;518.0183552168619;524.3524247554268;4232.640225580799;4240.978795106066;4308.763018034038;4326.978365108154;4405.273129933525;11726.648200553997;12019.278843269432;12039.63881083569;12073.396330183456;12101.515939362504;11691.883081169079;11935.462904169333;11977.401129943468;12002;12012.099092568093;8825.470471771714;8838.381313308135;8874.068222836639;8895.777605559913;8959.372843900865;6182.505399567934;6313.273973493107;6381.425671689571;6381.968090159519;6447.774327898501;10656.680299591024;10715.571377144826;10961.671149865517;10975;11115;10090.596376144871;10830.67507974762;11023.338599684044;11093.112550996062;11255.831162532404;7875.527468351917;8072.39457040736;8247.917520824714;8267.751967440987;8269.62786674589;8862.512561809162;9144.63421463134;9162.770930726518;9212.907870921474;9221.216196623362;7930.9603451982375;7990.3691974165695;8053.395995300259;8081;8278.130796266305;8069.435139540175;8144.063432705315;8190.344772418312;8301.418900676894;8421.284190843848;7449.664765085476;7515.6993720250575;7613.961930190314;7623.923760762544;7624.69501219945;5461.9180712290145;5503.807366742145;5553.583481238842;5652.067408877653;5766.711664416846;5601.6358936668985;5637.49262566371;5682.062459694104;5729.140628905698;5850.853728656818 08:24:34 INFO - PROCESS | 24142 | |4;prototype.html;341.2288228603356;342.0866287013674;344.435126392716;348.26863586468437;349.39380175395684;5589.217509548661;5674.205611214423;5675.772969081232;5691.373948865621;5696.544276457884;5476.205950137238;5572.247746554224;5580.748866301016;5585.218069470277;5589.245451863997;3926.528816542019;4190.371444283359;4354.608085272325;4361.738295702252;4363.690892732167;4336.674749393792;4345.6523478121835;4353.673474489426;4356.237658409781;4363.890902727426;4332.631726303252;4335.176316499871;4394.165108629366;4413.514513403523;4424.446944131983;4128.153728485653;4285.957140428587;4343.73937563744;4424.77876106195;4442.755648439351;3780.4896338994436;3783.6405541473514;3790.8437926432425;3798.582155962842;3818.6372294631965;5459.972700136514;5479.53423958964;5484.91772623411;5492.5605951523785;5512.972435137839;4896.779644915951;4904;4956.306117143567;4965.528274813887;4991.700497970133;5791;5792.160136780185;5796.565257605696;5798.9420105799245;5805.245318108618;4141.979290103561;4178.582141785828;4189.790510474464;4198.433211516437;4202.327627579603;5949.524038076944;6069.999999999955;6110.725017374184;6117.418845209698;6137.969310153465;4259.339802330644;4260.765657888784;4278.614924656796;4284.335927931175;4285.207236661227;4367.235733746582;4376.868693939124;4387.363832244307;4387.583179597933;4390.121975604892;4668.15973124834;4901.3335465941755;4926.187179115477;4997.700137991732;5030;5820.864931338433;5829;5829.941700583025;5886.676232807236;5889.646621202742;1458.1178387075665;1479.3712672114523;1479.9122644856022;1485.7177136344276;1488.8436714145075;953.952302384878;977.7702239973601;980.8234517786724;1022.8414595737523;1043.8121138195197;5655.745491452894;5660.745266462936;5670.432956704379;5681;5713.8285851424525;5948.910766338508;5984.708410374276;5989.520838332923;6022.156898034279;6028.186194863725;5423.83387571674;5727.3699893011735;5757.6833274169485;5789.594728368974;5798.565107616945;3171.365726854592;3190.601174853143;3199.840007999591;3204.8077115373153;3212.4217640824877;6083.422074902877;6201.534884883652;6207.6585787781205;6228.439440450381;6249.6562689051625;5913.994620914455;6166.907496387557;6175.475084617768;6180.412860778219;6185.659788711664;6106.6336019838955;6142.754289828446;6201.162843016225;6223.38192070056;6227.96886015567;6030.070377479165;6034.155218269358;6045.974598509549;6068.4841788448475;6069.33237343883;5820.592558520862;5913.970430147821;5929.318128415288;5966.597849505276;5992.910106348496;6177.876442471215;6200.131981522681;6242.3133455320785;6251.874962500634;6262.874742505033;4076.8776936691947;4232.703710740218;4241.6606671466825;4254.042840360894;4261.893452663709;5942.741758131882;5972.462478376967;5978.787848496085;5982.371850955587;5984.730687119177;5862.765489380377;5873.823785286449;5877.23595932526;5882.23530940975;5912.260967379078;5655.660660360391;5803.216565763568;5846.6784326862435;5848.766049357978;5853.765849365977;5838.095095260242;5848.619839710405;5848.736806843617;5850.473457388855;5856.9121463178935;5568.693721845338;5880.441358071148;5894.528437725057;5897.764089436546;5914.763409463573;5647.774089036392;5736.942630573641;5754.597178197486;5767.597117700577;5775.27809023872 08:24:34 INFO - PROCESS | 24142 | |5;yui.html;889.09756597054;892.07224486534;902.4630344944763;906.388187973118;953.5089428944095;17618;17740.467785966408;18303.72544411835;18326.541836454096;18701.064946752675;16782.496525104267;16949.57626059349;17044.403445879398;17062.82937170628;17090.829091709078;744.5458270455018;862.6764963138822;870.2124577257592;874.4665753890122;903.3992395057279;884.6151923913086;893.0176805513947;895.1764376773384;896.1262768800433;898.371140201858;926.5413620257982;927.8608208768705;929.944203347798;930.2976252929029;931.2596485793777;925.9907400925972;926.3376685669745;927.3091546797611;927.5362318840613;927.5825878354767;673.7237732529688;673.7810211681223;676.3878689785752;676.4047638078508;677.4411110833532;15212.771808422882;15256.23718814066;15307.158106304149;15437;15447.38210471591;16848.83151168485;17350.826491735046;17371.478855634352;17716.468505944842;18247.087645617667;13131.146475479063;13280.734385312337;13329.73340533184;13471.730565388736;13585.59243222705;4089.9591004089875;4176.4570605821045;4202.70581059326;4208.431861698662;4266.850660226877;89527.55236223842;89709.55145224297;90993.54503227508;91381.54309228477;91969.54015229881;12644.620661380173;12749.808752868714;12787.296698681661;12863.421146048264;12913.483460661657;25735.227943161735;25757.098501552355;25800.870995645277;25860.48279034409;26276.343091422485;9394.389364691273;9561;9738.269629777653;9770.413775173512;10066.396016239049;5111.514406131486;5123.923141152885;5235.110031294689;5246.501582349671;5271.103912334912;3706.7961262130843;3830.7893065881085;3852.8844134675446;3882.7476214045996;3895.104126051024;726.4587882027818;756.7729681095759;758.5941521286086;758.605525126942;778.3578547698172;671.4796033074407;681.4037716997627;725.9818504537429;733.2484203691153;749.9550026998288;10460.895391046144;10958.28771129874;11045.447727613588;11576.536938522533;11683.88316116828;657.4149007383432;664.4020381656547;716.4411758828122;802.9277365037175;806.0931452116369;5323;5332.866678333073;5344.678654050467;5374.596905232123;5377.919331210035;130270.34864825616;132944.33527832298;135032;136082.3195884014;137919.31040344734;110178.89821101686;112398.43800781107;113022.43488782503;115002;117694.41152794182;98672.50663746778;100444;101754.94735157957;102022;104594;9777.511124443892;9918.256130790076;10113.29206955506;10216.131628811338;10372.688819335432;57891;58069.99999999831;58548.70725646344;59669.701651491465;60629.39370606237;78942.2105778935;86277.1372286269;87028;88749.55625221833;89081.55459222662;12226.816597751218;12476.812847807472;12489.437975291316;12743.93628031854;12858.228506289652;14090;14103.506377276746;14123.435062597791;14207.147571145764;14365;14023.088499247517;14098.224597647228;14131.364088615832;15154.469593564172;15163.924180379028;14292.35684394184;14953.40186392532;14983.925080374964;15578;15766.211689415713;14017.649558761113;14522.273886305853;14606.561803145923;14708.779368309699;15071.698566028399;12025.579104731292;12233.204841685261;12352.320622365496;12627.93686031564;12870.227786332476;12263.816042759187;12721.618351449471;13092.5417610387;13234.536791212262;13409.128406653535 08:24:34 INFO - PROCESS | 24142 | __end_tp_report 08:24:34 INFO - PROCESS | 24142 | __start_cc_report 08:24:34 INFO - PROCESS | 24142 | _x_x_mozilla_cycle_collect,1037 08:24:34 INFO - PROCESS | 24142 | __end_cc_report 08:24:34 INFO - PROCESS | 24142 | __startTimestamp1476372274676__endTimestamp 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | ------- Summary: start ------- 08:24:34 INFO - PROCESS | 24142 | Number of tests: 6 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#0] dojo.html Cycles:165 Average:5561.81 Median:5798.25 stddev:634.10 (10.9%) stddev-sans-first:608.41 08:24:34 INFO - PROCESS | 24142 | Values: 3201.2 3203.7 3239.4 3248.8 3271.2 5541.8 5636.6 5638.7 5648.7 5656.9 5603.7 5621.7 5688.8 5699.8 5712.9 5093.0 5122.9 5147.4 5178.0 5183.2 5022.8 5184.7 5200.5 5207.2 5231.8 4987.5 5011.7 5039.5 5043.7 5074.3 5039.7 5056.8 5085.6 5161.6 5189.2 4999.3 5070.7 5117.6 5122.1 5138.8 5386.5 5424.0 5441.3 5456.9 5505.6 5857.4 5861.8 5920.2 5963.6 5969.1 5884.7 5981.3 5994.7 5995.6 5997.8 5545.2 5558.6 5598.6 5636.0 5664.8 6167.2 6193.4 6199.8 6203.3 6203.8 5759.6 5769.6 5946.6 5974.8 5997.2 5904.0 5958.3 5962.3 5989.5 5994.2 5927.6 5931.1 5931.7 5933.3 5938.8 5798.2 5800.8 5945.3 5946.4 5950.1 4520.3 4539.9 4548.6 4563.8 4570.5 4032.1 4034.7 4045.0 4054.6 4076.0 5608.4 5612.2 5666.6 5704.5 5724.0 6006.5 6013.1 6023.5 6026.8 6033.3 5717.8 5722.2 5727.7 5732.5 5732.7 5097.4 5097.7 5170.1 5175.3 5180.5 6124.9 6224.3 6236.5 6239.9 6246.6 5925.6 5956.6 5985.5 5996.9 6036.2 5900.3 6036.2 6068.3 6159.5 6172.0 5828.2 5959.3 5967.3 5967.4 5993.8 6058.0 6060.7 6060.9 6063.2 6064.4 5782.7 5810.0 5904.4 5904.6 5917.3 5801.6 5884.9 5886.4 5909.6 5923.3 5869.2 5869.7 5870.6 5873.9 5875.4 5750.0 5802.1 5886.8 5892.3 5945.2 5738.5 5789.0 5789.2 5798.6 5838.4 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#1] ext.html Cycles:180 Average:68278.41 Median:40013.30 stddev:142217.20 (355.4%) stddev-sans-first:142525.36 08:24:34 INFO - PROCESS | 24142 | Values: 592.9 627.1 653.8 660.2 661.8 34711.5 37432.0 37542.0 37570.8 47345.8 44263.3 47555.7 47652.5 47654.0 48054.5 9205.4 9335.9 9390.0 9390.1 9391.9 8544.7 9220.5 9323.9 10974.2 11216.6 11450.8 11526.7 11529.0 11530.7 11545.6 11302.3 11358.4 11360.1 11364.0 11368.0 11141.9 11195.4 11215.3 11219.0 11222.4 44335.0 44978.3 45004.1 45033.8 45057.0 3872.5 3921.9 3954.1 4297.9 4614.7 33887.7 35607.6 35716.3 35860.8 35988.5 4586.2 4598.1 4608.7 4620.7 4702.7 383888.0 386939.0 389012.0 390037.0 391160.0 69688.0 70176.3 70642.6 70711.6 70863.6 40617.8 41129.4 41245.0 72345.0 72742.0 16987.7 17138.4 17143.1 17147.2 17157.3 12800.8 12865.0 12867.4 12873.5 12884.9 19027.4 21648.0 22218.3 22927.4 23005.7 8151.8 8215.8 8277.8 8336.9 8367.3 1035.6 1100.5 1160.4 1190.9 1192.6 35293.6 35630.8 35899.7 36020.0 36037.3 1122.4 1133.4 1138.7 1151.8 1161.2 17989.8 18359.9 18417.6 18443.5 18525.8 560282.0 566278.0 567638.0 637827.0 991958.0 66845.7 67443.3 67448.3 67497.7 67512.0 1332.5 1421.5 1457.3 1457.8 1501.8 31094.8 35684.1 35811.0 36125.8 36179.5 56376.0 57062.7 57111.1 57195.1 57232.4 477640.0 478215.0 479956.0 483928.0 486402.0 31461.5 32169.2 32211.2 32392.0 32627.7 53234.5 53259.5 53404.7 53969.0 54083.0 44656.3 46775.0 47144.5 47284.5 47852.5 45466.5 46263.0 46409.0 46728.5 47540.0 44147.6 44262.1 44469.0 44723.0 45535.5 38227.6 39992.0 40034.6 40377.8 41084.8 29194.7 29738.6 30251.0 30438.8 30998.7 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#2] jquery.html Cycles:180 Average:30357.16 Median:22400.61 stddev:83996.77 (375.0%) stddev-sans-first:84202.26 08:24:34 INFO - PROCESS | 24142 | Values: 388.2 392.2 411.7 413.9 432.3 14829.6 18600.3 20537.3 20592.3 20655.6 15695.5 18593.0 18681.5 18734.6 19435.7 6078.5 6230.3 6233.5 6236.8 6266.9 6135.2 6158.6 6180.8 6183.6 6194.1 5983.4 5986.2 5993.4 6030.4 6134.6 6033.9 6097.3 6107.3 6135.2 6136.8 5503.3 5519.0 5523.4 5528.2 5536.6 16280.0 21611.6 22323.8 22477.4 23984.0 2481.0 2488.3 2492.7 2629.3 2946.7 10753.3 10976.2 11093.1 11136.4 11226.2 1756.6 1787.2 1826.5 1849.4 1878.3 84761.0 87034.1 87494.6 87830.6 88122.1 11418.4 11593.3 11760.1 11941.7 11943.5 16882.5 16969.5 16989.7 16999.3 17013.4 7765.7 8022.8 8137.6 8183.0 8402.2 7692.0 7763.1 7869.4 7997.7 8077.5 4683.7 4812.3 4968.2 5065.3 5086.4 2067.6 2128.0 2130.6 2150.2 2150.9 930.9 997.2 1028.0 1052.3 1082.2 18934.0 20193.0 20796.3 21151.2 21690.6 1039.2 1053.6 1066.6 1072.5 1082.6 10939.3 11850.3 11936.9 11970.6 12027.5 499723.0 500867.0 501102.0 519643.0 520134.0 95288.4 96549.0 97048.0 99603.0 100050.5 1012.7 1052.8 1060.2 1062.6 1073.0 18016.7 18502.5 18854.2 18950.1 19077.7 44154.3 44388.0 45415.5 46326.3 46606.8 63821.2 64530.7 64681.0 65798.0 66547.0 8792.8 8867.2 9189.6 9272.6 9386.7 8509.4 10217.5 13237.7 14011.9 14355.2 11945.7 12044.6 12502.4 12547.2 12769.6 11996.6 12132.0 12379.3 12414.4 12727.6 12391.8 12422.4 12497.5 12620.5 12667.4 8952.9 9062.0 9081.0 9256.8 9461.6 10199.0 11546.8 11605.4 11811.0 12132.2 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#3] mootools.html Cycles:180 Average:6189.71 Median:4545.76 stddev:3562.16 (78.4%) stddev-sans-first:3555.05 08:24:34 INFO - PROCESS | 24142 | Values: 1544.3 1586.4 1733.3 1804.9 1857.9 8123.6 8966.3 8970.0 9464.9 9540.9 8932.7 9347.6 9354.6 9363.9 9369.5 2399.0 2436.8 2444.5 2444.7 2446.6 2423.0 2426.3 2474.2 2475.2 2479.0 2389.2 2407.9 2421.8 2447.1 2451.0 2374.2 2379.6 2386.5 2391.5 2405.7 2351.2 2352.0 2365.4 2366.4 2372.8 9351.7 9370.7 9447.8 9456.0 9474.4 5473.1 5645.1 5646.8 5650.5 5659.5 3661.9 3674.2 3690.4 3695.5 3753.5 1336.6 1338.2 1343.1 1374.7 1377.3 12975.9 13043.3 13061.8 13070.9 13102.1 4875.2 4898.3 4937.4 4967.6 5112.4 11080.9 11118.6 11119.3 11125.9 11136.7 5570.2 5570.7 5572.7 5575.3 5575.4 4505.3 4586.2 4623.0 4960.2 5155.4 4320.2 4332.8 4334.3 4428.8 4443.1 1322.5 1322.9 1343.7 1362.1 1387.5 1055.3 1069.6 1080.9 1081.7 1096.2 6169.2 6247.8 6338.0 6347.5 6521.2 496.4 506.2 506.8 518.0 524.4 4232.6 4241.0 4308.8 4327.0 4405.3 11726.6 12019.3 12039.6 12073.4 12101.5 11691.9 11935.5 11977.4 12002.0 12012.1 8825.5 8838.4 8874.1 8895.8 8959.4 6182.5 6313.3 6381.4 6382.0 6447.8 10656.7 10715.6 10961.7 10975.0 11115.0 10090.6 10830.7 11023.3 11093.1 11255.8 7875.5 8072.4 8247.9 8267.8 8269.6 8862.5 9144.6 9162.8 9212.9 9221.2 7931.0 7990.4 8053.4 8081.0 8278.1 8069.4 8144.1 8190.3 8301.4 8421.3 7449.7 7515.7 7614.0 7623.9 7624.7 5461.9 5503.8 5553.6 5652.1 5766.7 5601.6 5637.5 5682.1 5729.1 5850.9 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#4] prototype.html Cycles:180 Average:4932.88 Median:5674.99 stddev:1458.52 (25.7%) stddev-sans-first:1421.32 08:24:34 INFO - PROCESS | 24142 | Values: 341.2 342.1 344.4 348.3 349.4 5589.2 5674.2 5675.8 5691.4 5696.5 5476.2 5572.2 5580.7 5585.2 5589.2 3926.5 4190.4 4354.6 4361.7 4363.7 4336.7 4345.7 4353.7 4356.2 4363.9 4332.6 4335.2 4394.2 4413.5 4424.4 4128.2 4286.0 4343.7 4424.8 4442.8 3780.5 3783.6 3790.8 3798.6 3818.6 5460.0 5479.5 5484.9 5492.6 5513.0 4896.8 4904.0 4956.3 4965.5 4991.7 5791.0 5792.2 5796.6 5798.9 5805.2 4142.0 4178.6 4189.8 4198.4 4202.3 5949.5 6070.0 6110.7 6117.4 6138.0 4259.3 4260.8 4278.6 4284.3 4285.2 4367.2 4376.9 4387.4 4387.6 4390.1 4668.2 4901.3 4926.2 4997.7 5030.0 5820.9 5829.0 5829.9 5886.7 5889.6 1458.1 1479.4 1479.9 1485.7 1488.8 954.0 977.8 980.8 1022.8 1043.8 5655.7 5660.7 5670.4 5681.0 5713.8 5948.9 5984.7 5989.5 6022.2 6028.2 5423.8 5727.4 5757.7 5789.6 5798.6 3171.4 3190.6 3199.8 3204.8 3212.4 6083.4 6201.5 6207.7 6228.4 6249.7 5914.0 6166.9 6175.5 6180.4 6185.7 6106.6 6142.8 6201.2 6223.4 6228.0 6030.1 6034.2 6046.0 6068.5 6069.3 5820.6 5914.0 5929.3 5966.6 5992.9 6177.9 6200.1 6242.3 6251.9 6262.9 4076.9 4232.7 4241.7 4254.0 4261.9 5942.7 5972.5 5978.8 5982.4 5984.7 5862.8 5873.8 5877.2 5882.2 5912.3 5655.7 5803.2 5846.7 5848.8 5853.8 5838.1 5848.6 5848.7 5850.5 5856.9 5568.7 5880.4 5894.5 5897.8 5914.8 5647.8 5736.9 5754.6 5767.6 5775.3 08:24:34 INFO - PROCESS | 24142 | 08:24:34 INFO - PROCESS | 24142 | [#5] yui.html Cycles:180 Average:23847.28 Median:25830.68 stddev:35010.33 (135.5%) stddev-sans-first:35066.10 08:24:34 INFO - PROCESS | 24142 | Values: 889.1 892.1 902.5 906.4 953.5 17618.0 17740.5 18303.7 18326.5 18701.1 16782.5 16949.6 17044.4 17062.8 17090.8 744.5 862.7 870.2 874.5 903.4 884.6 893.0 895.2 896.1 898.4 926.5 927.9 929.9 930.3 931.3 926.0 926.3 927.3 927.5 927.6 673.7 673.8 676.4 676.4 677.4 15212.8 15256.2 15307.2 15437.0 15447.4 16848.8 17350.8 17371.5 17716.5 18247.1 13131.1 13280.7 13329.7 13471.7 13585.6 4090.0 4176.5 4202.7 4208.4 4266.9 89527.6 89709.6 90993.5 91381.5 91969.5 12644.6 12749.8 12787.3 12863.4 12913.5 25735.2 25757.1 25800.9 25860.5 26276.3 9394.4 9561.0 9738.3 9770.4 10066.4 5111.5 5123.9 5235.1 5246.5 5271.1 3706.8 3830.8 3852.9 3882.7 3895.1 726.5 756.8 758.6 758.6 778.4 671.5 681.4 726.0 733.2 750.0 10460.9 10958.3 11045.4 11576.5 11683.9 657.4 664.4 716.4 802.9 806.1 5323.0 5332.9 5344.7 5374.6 5377.9 130270.3 132944.3 135032.0 136082.3 137919.3 110178.9 112398.4 113022.4 115002.0 117694.4 98672.5 100444.0 101754.9 102022.0 104594.0 9777.5 9918.3 10113.3 10216.1 10372.7 57891.0 58070.0 58548.7 59669.7 60629.4 78942.2 86277.1 87028.0 88749.6 89081.6 12226.8 12476.8 12489.4 12743.9 12858.2 14090.0 14103.5 14123.4 14207.1 14365.0 14023.1 14098.2 14131.4 15154.5 15163.9 14292.4 14953.4 14983.9 15578.0 15766.2 14017.6 14522.3 14606.6 14708.8 15071.7 12025.6 12233.2 12352.3 12627.9 12870.2 12263.8 12721.6 13092.5 13234.5 13409.1 08:24:34 INFO - PROCESS | 24142 | -------- Summary: end -------- 08:24:34 INFO - PROCESS | 24142 | 08:24:35 INFO - TEST-INFO | 24142: exit 0 08:24:35 INFO - TEST-OK | dromaeo_css | took 1112604ms 08:24:35 INFO - TEST-START | kraken 08:24:35 INFO - operating with platform_type : linux_ 08:24:35 INFO - Initialising browser for kraken test... 08:24:35 INFO - TEST-INFO | started process 24339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX68_bb/profile http://localhost:42466/getInfo.html) 08:24:39 INFO - PROCESS | 24339 | __metrics Screen width/height:1600/1200 08:24:39 INFO - PROCESS | 24339 | colorDepth:24 08:24:39 INFO - PROCESS | 24339 | Browser inner width/height: 1024/697 08:24:39 INFO - PROCESS | 24339 | __metrics 08:24:39 INFO - PROCESS | 24339 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:24:39 INFO - PROCESS | 24339 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:40 INFO - PROCESS | 24339 | console.error: PushService: 08:24:40 INFO - PROCESS | 24339 | stateChangeProcessEnqueue: Error transitioning state 08:24:40 INFO - PROCESS | 24339 | UnknownError 08:24:40 INFO - TEST-INFO | 24339: exit 0 08:24:40 INFO - Browser initialized. 08:24:40 INFO - Running cycle 1/1 for kraken test... 08:24:40 INFO - TEST-INFO | started process 24427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX68_bb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:52 INFO - PROCESS | 24427 | RSS: Main: 143552512 08:24:52 INFO - PROCESS | 24427 | 08:24:55 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42466/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:55 INFO - PROCESS | 24427 | RSS: Main: 198594560 08:24:55 INFO - PROCESS | 24427 | 08:24:58 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42466/tests/kraken/driver.html?testName=audio-dft) 08:24:59 INFO - PROCESS | 24427 | RSS: Main: 183975936 08:24:59 INFO - PROCESS | 24427 | 08:25:01 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42466/tests/kraken/driver.html?testName=audio-fft) 08:25:02 INFO - PROCESS | 24427 | RSS: Main: 274599936 08:25:02 INFO - PROCESS | 24427 | 08:25:04 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42466/tests/kraken/driver.html?testName=audio-oscillator) 08:25:05 INFO - PROCESS | 24427 | RSS: Main: 180236288 08:25:05 INFO - PROCESS | 24427 | 08:25:07 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42466/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:25:07 INFO - PROCESS | 24427 | RSS: Main: 179101696 08:25:07 INFO - PROCESS | 24427 | 08:25:10 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42466/tests/kraken/driver.html?testName=imaging-darkroom) 08:25:11 INFO - PROCESS | 24427 | RSS: Main: 214630400 08:25:11 INFO - PROCESS | 24427 | 08:25:14 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42466/tests/kraken/driver.html?testName=imaging-desaturate) 08:25:15 INFO - PROCESS | 24427 | RSS: Main: 213491712 08:25:15 INFO - PROCESS | 24427 | 08:25:18 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42466/tests/kraken/driver.html?testName=json-parse-financial) 08:25:18 INFO - PROCESS | 24427 | RSS: Main: 214200320 08:25:18 INFO - PROCESS | 24427 | 08:25:20 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42466/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:20 INFO - PROCESS | 24427 | RSS: Main: 176111616 08:25:20 INFO - PROCESS | 24427 | 08:25:22 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:23 INFO - PROCESS | 24427 | RSS: Main: 182345728 08:25:23 INFO - PROCESS | 24427 | 08:25:25 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:25:25 INFO - PROCESS | 24427 | RSS: Main: 189513728 08:25:25 INFO - PROCESS | 24427 | 08:25:28 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:28 INFO - PROCESS | 24427 | RSS: Main: 197959680 08:25:28 INFO - PROCESS | 24427 | 08:25:31 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:32 INFO - PROCESS | 24427 | RSS: Main: 194117632 08:25:32 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | Cycle 1(1): loaded http://localhost:42466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42466/tests/kraken/driver.html?testName=ai-astar) 08:25:33 INFO - PROCESS | 24427 | RSS: Main: 245207040 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | __start_tp_report 08:25:33 INFO - PROCESS | 24427 | _x_x_mozilla_page_load 08:25:33 INFO - PROCESS | 24427 | _x_x_mozilla_page_load_details 08:25:33 INFO - PROCESS | 24427 | |i|pagename|runs| 08:25:33 INFO - PROCESS | 24427 | |0;ai-astar;111;111;121;112;111;111;112;110;110;111 08:25:33 INFO - PROCESS | 24427 | |1;audio-beat-detection;149;117;148;185;117;148;142;117;172;116 08:25:33 INFO - PROCESS | 24427 | |2;audio-dft;161;160;162;161;160;161;160;160;161;159 08:25:33 INFO - PROCESS | 24427 | |3;audio-fft;75;75;75;171;75;74;75;75;163;75 08:25:33 INFO - PROCESS | 24427 | |4;audio-oscillator;83;84;145;83;84;84;147;84;83;145 08:25:33 INFO - PROCESS | 24427 | |5;imaging-gaussian-blur;116;116;116;117;117;117;117;116;117;118 08:25:33 INFO - PROCESS | 24427 | |6;imaging-darkroom;146;146;145;146;146;146;146;147;145;146 08:25:33 INFO - PROCESS | 24427 | |7;imaging-desaturate;96;96;95;94;95;95;95;95;95;95 08:25:33 INFO - PROCESS | 24427 | |8;json-parse-financial;50;51;51;52;51;52;58;49;51;50 08:25:33 INFO - PROCESS | 24427 | |9;json-stringify-tinderbox;61;62;60;62;61;60;61;61;62;61 08:25:33 INFO - PROCESS | 24427 | |10;stanford-crypto-aes;73;73;72;78;77;73;72;76;75;73 08:25:33 INFO - PROCESS | 24427 | |11;stanford-crypto-ccm;127;118;118;119;126;119;116;122;155;121 08:25:33 INFO - PROCESS | 24427 | |12;stanford-crypto-pbkdf2;158;156;156;156;152;156;151;151;151;153 08:25:33 INFO - PROCESS | 24427 | |13;stanford-crypto-sha256-iterative;61;70;62;61;61;64;61;63;60;63 08:25:33 INFO - PROCESS | 24427 | __end_tp_report 08:25:33 INFO - PROCESS | 24427 | __start_cc_report 08:25:33 INFO - PROCESS | 24427 | _x_x_mozilla_cycle_collect,1658 08:25:33 INFO - PROCESS | 24427 | __end_cc_report 08:25:33 INFO - PROCESS | 24427 | __startTimestamp1476372333899__endTimestamp 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | ------- Summary: start ------- 08:25:33 INFO - PROCESS | 24427 | Number of tests: 14 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#0] ai-astar Cycles:10 Average:112.00 Median:111.00 stddev:3.23 (2.9%) stddev-sans-first:3.41 08:25:33 INFO - PROCESS | 24427 | Values: 111.0 111.0 121.0 112.0 111.0 111.0 112.0 110.0 110.0 111.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:148.00 stddev:24.52 (16.6%) stddev-sans-first:25.84 08:25:33 INFO - PROCESS | 24427 | Values: 149.0 117.0 148.0 185.0 117.0 148.0 142.0 117.0 172.0 116.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 08:25:33 INFO - PROCESS | 24427 | Values: 161.0 160.0 162.0 161.0 160.0 161.0 160.0 160.0 161.0 159.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.00 stddev:38.89 (51.9%) stddev-sans-first:40.68 08:25:33 INFO - PROCESS | 24427 | Values: 75.0 75.0 75.0 171.0 75.0 74.0 75.0 75.0 163.0 75.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.50 stddev:30.00 (35.9%) stddev-sans-first:31.01 08:25:33 INFO - PROCESS | 24427 | Values: 83.0 84.0 145.0 83.0 84.0 84.0 147.0 84.0 83.0 145.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 08:25:33 INFO - PROCESS | 24427 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 118.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 08:25:33 INFO - PROCESS | 24427 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 08:25:33 INFO - PROCESS | 24427 | Values: 96.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.00 stddev:2.46 (4.8%) stddev-sans-first:2.55 08:25:33 INFO - PROCESS | 24427 | Values: 50.0 51.0 51.0 52.0 51.0 52.0 58.0 49.0 51.0 50.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 08:25:33 INFO - PROCESS | 24427 | Values: 61.0 62.0 60.0 62.0 61.0 60.0 61.0 61.0 62.0 61.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:2.15 (2.9%) stddev-sans-first:2.24 08:25:33 INFO - PROCESS | 24427 | Values: 73.0 73.0 72.0 78.0 77.0 73.0 72.0 76.0 75.0 73.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:121.50 stddev:11.42 (9.4%) stddev-sans-first:12.06 08:25:33 INFO - PROCESS | 24427 | Values: 127.0 118.0 118.0 119.0 126.0 119.0 116.0 122.0 155.0 121.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:156.00 stddev:2.67 (1.7%) stddev-sans-first:2.40 08:25:33 INFO - PROCESS | 24427 | Values: 158.0 156.0 156.0 156.0 152.0 156.0 151.0 151.0 151.0 153.0 08:25:33 INFO - PROCESS | 24427 | 08:25:33 INFO - PROCESS | 24427 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:2.88 (4.6%) stddev-sans-first:2.99 08:25:33 INFO - PROCESS | 24427 | Values: 61.0 70.0 62.0 61.0 61.0 64.0 61.0 63.0 60.0 63.0 08:25:33 INFO - PROCESS | 24427 | -------- Summary: end -------- 08:25:33 INFO - PROCESS | 24427 | 08:25:34 INFO - TEST-INFO | 24427: exit 0 08:25:34 INFO - TEST-OK | kraken | took 59555ms 08:25:34 INFO - SUITE-END | took 1172s 08:25:35 INFO - Completed test suite (00:19:32) 08:25:35 INFO - javascript benchmark 08:25:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.1676963989357, 3203.695648913353, 3239.433099207638, 3248.837558122096, 3271.2312606537475, 5541.833744987652, 5636.577256705748, 5638.746256418461, 5648.717564121798, 5656.915146272799, 5603.691796951176, 5621.662700237978, 5688.772449102042, 5699.829005129853, 5712.857178570538, 5092.999999999991, 5122.871928201806, 5147.408048074463, 5178.0, 5183.222516622518, 5022.773975171126, 5184.740762961856, 5200.453952335007, 5207.192885102815, 5231.816886408977, 4987.476314986929, 5011.699298042111, 5039.546440820325, 5043.722595257259, 5074.264231686403, 5039.72281524516, 5056.7977280908535, 5085.567726743231, 5161.61287903405, 5189.1697328427645, 4999.250112483121, 5070.72111033893, 5117.590592752571, 5122.052420302219, 5138.7687554059785, 5386.461353864622, 5423.972880135613, 5441.319835020622, 5456.945430545684, 5505.614606977513, 5857.384974577651, 5861.794837180721, 5920.171176035358, 5963.582549221556, 5969.104634304846, 5884.705764711747, 5981.342052374235, 5994.730237139295, 5995.640261584319, 5997.760089596454, 5545.1682247663175, 5558.583106267046, 5598.58010649203, 5636.04187288162, 5664.773409063674, 6167.190546293764, 6193.411625895533, 6199.814005579839, 6203.31763506014, 6203.8138855834395, 5759.625624334405, 5769.624974376736, 5946.554008449383, 5974.761009559656, 5997.160397544348, 5903.970480147572, 5958.255218097738, 5962.314333851576, 5989.490893274121, 5994.190784244159, 5927.614705044159, 5931.110333450017, 5931.73307201177, 5933.258342707161, 5938.762449501972, 5798.246227990334, 5800.767969281182, 5945.256842894638, 5946.375630558729, 5950.107483877453, 4520.299353600197, 4539.909201815945, 4548.613367863769, 4563.817447302071, 4570.520095390003, 4032.1330913853662, 4034.6973976951845, 4045.0, 4054.614811592894, 4075.9796201018803, 5608.355039170548, 5612.214289999403, 5666.575006874501, 5704.515116215168, 5723.971380143073, 6006.519478441714, 6013.128096426081, 6023.45788879003, 6026.849328766816, 6033.336333003366, 5717.799877004285, 5722.170285308607, 5727.684977326204, 5732.512736417451, 5732.713364331767, 5097.388313402415, 5097.6941383517105, 5170.121079416508, 5175.275461435402, 5180.507851754077, 6124.899720462962, 6224.253089629183, 6236.532260080512, 6239.937600624026, 6246.625202487865, 5925.555583331354, 5956.553258505716, 5985.521158307238, 5996.94175893688, 6036.245469316335, 5900.291964964232, 6036.245469316335, 6068.271807383142, 6159.476444502088, 6172.0, 5828.154917537018, 5959.28488581373, 5967.343592204941, 5967.433093856077, 5993.790217342372, 6057.9701450754355, 6060.6969651516365, 6060.878782424239, 6063.151158837678, 6064.363241859542, 5782.681952492653, 5809.970950145223, 5904.380040095728, 5904.645721256738, 5917.349091600006, 5801.593888427769, 5884.852878678067, 5886.352501224947, 5909.6158749681135, 5923.348431672599, 5869.207656966255, 5869.706514674163, 5870.618409803349, 5873.882522349614, 5875.383084776037, 5750.022496175577, 5802.100674395307, 5886.764529418775, 5892.292924849045, 5945.227120474484, 5738.454846789548, 5789.0, 5789.189513468202, 5798.56510761703, 5838.416158384128], "value": 5518.595113251817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.8814237152568, 627.0562802981513, 653.8169312592473, 660.1780782925256, 661.8477750117476, 34711.47932781007, 37432.0, 37542.0, 37570.812145939264, 47345.7632711836, 44263.336049959275, 47555.6711030228, 47652.52347476524, 47654.0, 48054.51945480544, 9205.401648892834, 9335.906640933572, 9389.953050234739, 9390.107939745712, 9391.85912211314, 8544.658213671446, 9220.492872892022, 9323.906760932405, 10974.176936729737, 11216.551337946472, 11450.770984580304, 11526.65420037396, 11529.0, 11530.654080377602, 11545.595904143356, 11302.321860688344, 11358.375289359083, 11360.091192704605, 11364.0, 11368.033717134054, 11141.888581114166, 11195.384253866034, 11215.271007384576, 11219.0, 11222.382768947704, 44335.0, 44978.325325120146, 45004.09991800178, 45033.77483112564, 45057.0, 3872.477215575889, 3921.941170882439, 3954.110325176841, 4297.935530967038, 4614.676972611918, 33887.661123388694, 35607.64392356069, 35716.28567428663, 35860.82069589661, 35988.460173097425, 4586.243269860469, 4598.080383923195, 4608.677392582521, 4620.676552641316, 4702.670813043088, 383888.0, 386939.0, 389012.0, 390037.0, 391159.99999999715, 69688.0, 70176.29823701698, 70642.64678676573, 70711.64644176746, 70863.64568177126, 40617.796911015845, 41129.383059254134, 41245.0, 72345.0, 72742.0, 16987.74518382225, 17138.40015599448, 17143.057131857866, 17147.22837472292, 17157.313707451813, 12800.80798788019, 12865.0, 12867.42096605655, 12873.549425770054, 12884.871151288555, 19027.401984834556, 21648.0, 22218.333449996528, 22927.426814329774, 23005.65491517595, 8151.755447336589, 8215.794605134919, 8277.793055173668, 8336.916630833735, 8367.330613550901, 1035.570238351076, 1100.5322737836389, 1160.4313886195703, 1190.898773604236, 1192.582596091361, 35293.64706352955, 35630.821845890605, 35899.66348122158, 36020.0, 36037.27925441477, 1122.3770807201918, 1133.4276190523733, 1138.6527109231763, 1151.8272259161026, 1161.1639619474133, 17989.820101799076, 18359.90820045891, 18417.631647366976, 18443.538911527052, 18525.81474185241, 560282.0, 566278.0, 567638.0, 637827.0, 991958.0, 66845.66577167083, 67443.3255667437, 67448.32551674519, 67497.66251168713, 67512.0, 1332.460353556811, 1421.5024741340449, 1457.3296283709612, 1457.7521821290406, 1501.8347981722002, 31094.844525777225, 35684.107897301736, 35811.0, 36125.81937090298, 36179.457308140925, 56376.0, 57062.714686427964, 57111.14333284921, 57195.14207286811, 57232.42767572271, 477640.0, 478215.0, 479956.0, 483928.0, 486402.0, 31461.528077078405, 32169.195770105936, 32211.194720131247, 32392.0, 32627.673723262462, 53234.46765532295, 53259.46740532545, 53404.73297633487, 53969.0, 54083.0, 44656.33015504835, 46775.0, 47144.528554714016, 47284.52715472801, 47852.5214747862, 45466.545334547554, 46263.0, 46409.0, 46728.532714672416, 47540.0, 44147.558524414344, 44262.114757704025, 44469.0, 44723.0, 45535.54464455313, 38227.617723822405, 39992.0, 40034.59965400309, 40377.79811100926, 41084.79457602693, 29194.70805292005, 29738.553921691626, 30251.0, 30438.84780576083, 30998.690013099582], "value": 22200.47578189641, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.22161566060043, 392.16077593948955, 411.6542104632109, 413.8592878421337, 432.3104648086301, 14829.55511334659, 18600.25598976041, 20537.281195158153, 20592.279270225525, 20655.586888262227, 15695.45065922693, 18593.0, 18681.532961675966, 18734.625307493843, 19435.708464373045, 6078.452939235468, 6230.25236971564, 6233.50131989442, 6236.750529978795, 6266.905996410058, 6135.171751813515, 6158.56890017699, 6180.84547886302, 6183.628982261056, 6194.070889366609, 5983.371745966654, 5986.161937328778, 5993.400659933994, 6030.427109424598, 6134.631922084689, 6033.879322413527, 6097.3292937776805, 6107.297660769002, 6135.202423684915, 6136.815895523119, 5503.284573005483, 5518.978988887069, 5523.392426833086, 5528.170774383834, 5536.584756143306, 16280.0, 21611.567768644538, 22323.776762232334, 22477.438064048365, 23984.0, 2480.987595062013, 2488.340589743719, 2492.7382624824313, 2629.3032346428213, 2946.7347938685407, 10753.30103543275, 10976.176786740947, 11093.057090147347, 11136.443177841156, 11226.158038147172, 1756.6135450200936, 1787.2046939112101, 1826.4794533558006, 1849.3619701203063, 1878.3331917169344, 84761.0, 87034.08346624418, 87494.56252718759, 87830.56084719536, 88122.11877881203, 11418.429078546205, 11593.304401735923, 11760.117991150699, 11941.70145746346, 11943.522259109539, 16882.493525194262, 16969.490915272563, 16989.66020679604, 16999.320027198773, 17013.404530841362, 7765.689372425039, 8022.83954320922, 8137.633806478722, 8183.0, 8402.201790829984, 7692.0384951881015, 7763.0684317882215, 7869.370450363957, 7997.720079797179, 8077.474964127313, 4683.742394168354, 4812.326274321597, 4968.155413579628, 5065.3161823153405, 5086.415062267813, 2067.6071546406133, 2128.0, 2130.552583957355, 2150.172183709283, 2150.860194087379, 930.8603709443502, 997.2470784557613, 1027.984580231297, 1052.2634156090767, 1082.1775450657399, 18934.0, 20193.0, 20796.272130475663, 21151.153953841673, 21690.566188676137, 1039.184240371305, 1053.5891002509027, 1066.5893630951985, 1072.485207100596, 1082.5615625671458, 10939.28894621847, 11850.348230847387, 11936.940315298369, 11970.58102966392, 12027.518899244107, 499723.0, 500867.0, 501102.0, 519643.0, 520134.0, 95288.39721637101, 96549.0, 97048.0, 99603.0, 100050.4997475008, 1012.7366884609908, 1052.8210204265292, 1060.2313322841032, 1062.6174577152374, 1072.9570817167225, 18016.729749053513, 18502.537436563656, 18854.24583016664, 18950.13644257008, 19077.71383429222, 44154.337684935395, 44388.0, 45415.54584454245, 46326.30510542412, 46606.76696616495, 63821.22326169961, 64530.67734661297, 64681.0, 65798.0, 66547.00179497215, 8792.824143517222, 8867.201951824367, 9189.632414703605, 9272.58273377686, 9386.67146649864, 8509.44688595264, 10217.540210690388, 13237.735245295233, 14011.859881401464, 14355.210463424195, 11945.701357466132, 12044.638660840188, 12502.374881255719, 12547.184433011826, 12769.55306564303, 11996.64010079699, 12132.0, 12379.319137447526, 12414.392977474832, 12727.618171454871, 12391.814122788346, 12422.440990155645, 12497.500099995897, 12620.495180192689, 12667.366631668565, 8952.865707014531, 9061.954690226507, 9080.954595226982, 9256.768580785534, 9461.621535138518, 10199.0, 11546.82679759821, 11605.419729013684, 11811.0, 12132.150749447454], "value": 9608.285653460682, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.3359355477144, 1586.4368149306995, 1733.2633630706955, 1804.9278028878846, 1857.9256829726812, 8123.634436450359, 8966.327525435601, 8969.955150224248, 9464.905350946497, 9540.90459095409, 8932.687355942544, 9347.579358928848, 9354.579043943022, 9363.876334839812, 9369.48467834269, 2399.0, 2436.8050555955483, 2444.4744379958306, 2444.706635203778, 2446.6207737800582, 2422.9636555451684, 2426.34488688055, 2474.2329877737957, 2475.207933461293, 2478.950420991579, 2389.247387073075, 2407.9036838526526, 2421.83047186697, 2447.106806015809, 2451.007342026482, 2374.2046414451006, 2379.6311571706415, 2386.486905315357, 2391.5097405031925, 2405.711314642254, 2351.1770880191966, 2351.9647205291935, 2365.3731761083327, 2366.420227044368, 2372.79831214347, 9351.672691455766, 9370.718878433658, 9447.763805904839, 9455.952720236355, 9474.38416502925, 5473.0969390050595, 5645.096784514458, 5646.802361917313, 5650.491455768959, 5659.51894089003, 3661.9267614647556, 3674.173311005029, 3690.447232164577, 3695.519582454264, 3753.493278407407, 1336.6057013181037, 1338.2372047932681, 1343.0799902067115, 1374.6838227207797, 1377.2838124175373, 12975.935120324433, 13043.282619455926, 13061.804072938914, 13070.934645326808, 13102.08285420021, 4875.195592727231, 4898.2652602109965, 4937.432195297515, 4967.552920237196, 5112.3865136183895, 11080.889191107986, 11118.61084862026, 11119.332840029625, 11125.888741112647, 11136.721581960515, 5570.164475328733, 5570.749316280696, 5572.665640061528, 5575.303087114111, 5575.386707462176, 4505.324201369755, 4586.194739481705, 4622.976885115553, 4960.181570040902, 5155.381354237516, 4320.200762858881, 4332.848350307724, 4334.28484300087, 4428.822847086145, 4443.0669559392245, 1322.4842311498523, 1322.8544860065385, 1343.7446885091802, 1362.1486570893192, 1387.458891032498, 1055.303499690216, 1069.6256310291492, 1080.8756992945755, 1081.6538707613645, 1096.17786660005, 6169.2288463942, 6247.75008999644, 6338.01760727088, 6347.460465860361, 6521.152250207538, 496.38448324078604, 506.21031191341626, 506.8150125204348, 518.0183552168619, 524.3524247554268, 4232.640225580799, 4240.978795106066, 4308.763018034038, 4326.978365108154, 4405.273129933525, 11726.648200553997, 12019.278843269432, 12039.63881083569, 12073.396330183456, 12101.515939362504, 11691.883081169079, 11935.462904169333, 11977.401129943468, 12002.0, 12012.099092568093, 8825.470471771714, 8838.381313308135, 8874.068222836639, 8895.777605559913, 8959.372843900865, 6182.505399567934, 6313.273973493107, 6381.425671689571, 6381.968090159519, 6447.774327898501, 10656.680299591024, 10715.571377144826, 10961.671149865517, 10975.0, 11115.0, 10090.596376144871, 10830.67507974762, 11023.338599684044, 11093.112550996062, 11255.831162532404, 7875.527468351917, 8072.39457040736, 8247.917520824714, 8267.751967440987, 8269.62786674589, 8862.512561809162, 9144.63421463134, 9162.770930726518, 9212.907870921474, 9221.216196623362, 7930.9603451982375, 7990.3691974165695, 8053.395995300259, 8081.0, 8278.130796266305, 8069.435139540175, 8144.063432705315, 8190.344772418312, 8301.418900676894, 8421.284190843848, 7449.664765085476, 7515.6993720250575, 7613.961930190314, 7623.923760762544, 7624.69501219945, 5461.9180712290145, 5503.807366742145, 5553.583481238842, 5652.067408877653, 5766.711664416846, 5601.6358936668985, 5637.49262566371, 5682.062459694104, 5729.140628905698, 5850.853728656818], "value": 4875.790843921112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.2288228603356, 342.0866287013674, 344.435126392716, 348.26863586468437, 349.39380175395684, 5589.217509548661, 5674.205611214423, 5675.772969081232, 5691.373948865621, 5696.544276457884, 5476.205950137238, 5572.247746554224, 5580.748866301016, 5585.218069470277, 5589.245451863997, 3926.528816542019, 4190.371444283359, 4354.608085272325, 4361.738295702252, 4363.690892732167, 4336.674749393792, 4345.6523478121835, 4353.673474489426, 4356.237658409781, 4363.890902727426, 4332.631726303252, 4335.176316499871, 4394.165108629366, 4413.514513403523, 4424.446944131983, 4128.153728485653, 4285.957140428587, 4343.73937563744, 4424.77876106195, 4442.755648439351, 3780.4896338994436, 3783.6405541473514, 3790.8437926432425, 3798.582155962842, 3818.6372294631965, 5459.972700136514, 5479.53423958964, 5484.91772623411, 5492.5605951523785, 5512.972435137839, 4896.779644915951, 4904.0, 4956.306117143567, 4965.528274813887, 4991.700497970133, 5791.0, 5792.160136780185, 5796.565257605696, 5798.9420105799245, 5805.245318108618, 4141.979290103561, 4178.582141785828, 4189.790510474464, 4198.433211516437, 4202.327627579603, 5949.524038076944, 6069.999999999955, 6110.725017374184, 6117.418845209698, 6137.969310153465, 4259.339802330644, 4260.765657888784, 4278.614924656796, 4284.335927931175, 4285.207236661227, 4367.235733746582, 4376.868693939124, 4387.363832244307, 4387.583179597933, 4390.121975604892, 4668.15973124834, 4901.3335465941755, 4926.187179115477, 4997.700137991732, 5030.0, 5820.864931338433, 5829.0, 5829.941700583025, 5886.676232807236, 5889.646621202742, 1458.1178387075665, 1479.3712672114523, 1479.9122644856022, 1485.7177136344276, 1488.8436714145075, 953.952302384878, 977.7702239973601, 980.8234517786724, 1022.8414595737523, 1043.8121138195197, 5655.745491452894, 5660.745266462936, 5670.432956704379, 5681.0, 5713.8285851424525, 5948.910766338508, 5984.708410374276, 5989.520838332923, 6022.156898034279, 6028.186194863725, 5423.83387571674, 5727.3699893011735, 5757.6833274169485, 5789.594728368974, 5798.565107616945, 3171.365726854592, 3190.601174853143, 3199.840007999591, 3204.8077115373153, 3212.4217640824877, 6083.422074902877, 6201.534884883652, 6207.6585787781205, 6228.439440450381, 6249.6562689051625, 5913.994620914455, 6166.907496387557, 6175.475084617768, 6180.412860778219, 6185.659788711664, 6106.6336019838955, 6142.754289828446, 6201.162843016225, 6223.38192070056, 6227.96886015567, 6030.070377479165, 6034.155218269358, 6045.974598509549, 6068.4841788448475, 6069.33237343883, 5820.592558520862, 5913.970430147821, 5929.318128415288, 5966.597849505276, 5992.910106348496, 6177.876442471215, 6200.131981522681, 6242.3133455320785, 6251.874962500634, 6262.874742505033, 4076.8776936691947, 4232.703710740218, 4241.6606671466825, 4254.042840360894, 4261.893452663709, 5942.741758131882, 5972.462478376967, 5978.787848496085, 5982.371850955587, 5984.730687119177, 5862.765489380377, 5873.823785286449, 5877.23595932526, 5882.23530940975, 5912.260967379078, 5655.660660360391, 5803.216565763568, 5846.6784326862435, 5848.766049357978, 5853.765849365977, 5838.095095260242, 5848.619839710405, 5848.736806843617, 5850.473457388855, 5856.9121463178935, 5568.693721845338, 5880.441358071148, 5894.528437725057, 5897.764089436546, 5914.763409463573, 5647.774089036392, 5736.942630573641, 5754.597178197486, 5767.597117700577, 5775.27809023872], "value": 4465.74072697512, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.09756597054, 892.07224486534, 902.4630344944763, 906.388187973118, 953.5089428944095, 17618.0, 17740.467785966408, 18303.72544411835, 18326.541836454096, 18701.064946752675, 16782.496525104267, 16949.57626059349, 17044.403445879398, 17062.82937170628, 17090.829091709078, 744.5458270455018, 862.6764963138822, 870.2124577257592, 874.4665753890122, 903.3992395057279, 884.6151923913086, 893.0176805513947, 895.1764376773384, 896.1262768800433, 898.371140201858, 926.5413620257982, 927.8608208768705, 929.944203347798, 930.2976252929029, 931.2596485793777, 925.9907400925972, 926.3376685669745, 927.3091546797611, 927.5362318840613, 927.5825878354767, 673.7237732529688, 673.7810211681223, 676.3878689785752, 676.4047638078508, 677.4411110833532, 15212.771808422882, 15256.23718814066, 15307.158106304149, 15437.0, 15447.38210471591, 16848.83151168485, 17350.826491735046, 17371.478855634352, 17716.468505944842, 18247.087645617667, 13131.146475479063, 13280.734385312337, 13329.73340533184, 13471.730565388736, 13585.59243222705, 4089.9591004089875, 4176.4570605821045, 4202.70581059326, 4208.431861698662, 4266.850660226877, 89527.55236223842, 89709.55145224297, 90993.54503227508, 91381.54309228477, 91969.54015229881, 12644.620661380173, 12749.808752868714, 12787.296698681661, 12863.421146048264, 12913.483460661657, 25735.227943161735, 25757.098501552355, 25800.870995645277, 25860.48279034409, 26276.343091422485, 9394.389364691273, 9561.0, 9738.269629777653, 9770.413775173512, 10066.396016239049, 5111.514406131486, 5123.923141152885, 5235.110031294689, 5246.501582349671, 5271.103912334912, 3706.7961262130843, 3830.7893065881085, 3852.8844134675446, 3882.7476214045996, 3895.104126051024, 726.4587882027818, 756.7729681095759, 758.5941521286086, 758.605525126942, 778.3578547698172, 671.4796033074407, 681.4037716997627, 725.9818504537429, 733.2484203691153, 749.9550026998288, 10460.895391046144, 10958.28771129874, 11045.447727613588, 11576.536938522533, 11683.88316116828, 657.4149007383432, 664.4020381656547, 716.4411758828122, 802.9277365037175, 806.0931452116369, 5323.0, 5332.866678333073, 5344.678654050467, 5374.596905232123, 5377.919331210035, 130270.34864825616, 132944.33527832298, 135032.0, 136082.3195884014, 137919.31040344734, 110178.89821101686, 112398.43800781107, 113022.43488782503, 115002.0, 117694.41152794182, 98672.50663746778, 100444.0, 101754.94735157957, 102022.0, 104594.0, 9777.511124443892, 9918.256130790076, 10113.29206955506, 10216.131628811338, 10372.688819335432, 57891.0, 58069.99999999831, 58548.70725646344, 59669.701651491465, 60629.39370606237, 78942.2105778935, 86277.1372286269, 87028.0, 88749.55625221833, 89081.55459222662, 12226.816597751218, 12476.812847807472, 12489.437975291316, 12743.93628031854, 12858.228506289652, 14090.0, 14103.506377276746, 14123.435062597791, 14207.147571145764, 14365.0, 14023.088499247517, 14098.224597647228, 14131.364088615832, 15154.469593564172, 15163.924180379028, 14292.35684394184, 14953.40186392532, 14983.925080374964, 15578.0, 15766.211689415713, 14017.649558761113, 14522.273886305853, 14606.561803145923, 14708.779368309699, 15071.698566028399, 12025.579104731292, 12233.204841685261, 12352.320622365496, 12627.93686031564, 12870.227786332476, 12263.816042759187, 12721.618351449471, 13092.5417610387, 13234.536791212262, 13409.128406653535], "value": 8397.001617408683, "unit": "score"}], "name": "dromaeo_css", "value": 7741.48132060573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 121.0, 112.0, 111.0, 111.0, 112.0, 110.0, 110.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 148.0, 185.0, 117.0, 148.0, 142.0, 117.0, 172.0, 116.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 171.0, 75.0, 74.0, 75.0, 75.0, 163.0, 75.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 145.0, 83.0, 84.0, 84.0, 147.0, 84.0, 83.0, 145.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 52.0, 51.0, 52.0, 58.0, 49.0, 51.0, 50.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 60.0, 62.0, 61.0, 60.0, 61.0, 61.0, 62.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 78.0, 77.0, 73.0, 72.0, 76.0, 75.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 118.0, 118.0, 119.0, 126.0, 119.0, 116.0, 122.0, 155.0, 121.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 156.0, 156.0, 156.0, 152.0, 156.0, 151.0, 151.0, 151.0, 153.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 70.0, 62.0, 61.0, 61.0, 64.0, 61.0, 63.0, 60.0, 63.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1494.3, "alertThreshold": 2.0}]} 08:25:35 INFO - Return code: 0 08:25:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:35 INFO - # TBPL SUCCESS # 08:25:35 INFO - Running post-action listener: _resource_record_post_action 08:25:35 INFO - [mozharness: 2016-10-13 15:25:35.202081Z] Finished run-tests step (success) 08:25:35 INFO - Running post-run listener: _resource_record_post_run 08:25:35 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291631104; Read time: 16; Write time: 190036 08:25:35 INFO - TinderboxPrint: CPU usage
12.9% 08:25:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:25:35 INFO - TinderboxPrint: I/O write bytes / time
291,631,104 / 190,036 08:25:35 INFO - TinderboxPrint: CPU idle
8,246.7 (87.1%) 08:25:35 INFO - TinderboxPrint: CPU user
1,179.4 (12.5%) 08:25:35 INFO - TinderboxPrint: Swap in / out
0 / 0 08:25:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 66301952; Read time: 0; Write time: 74968 08:25:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 225329152; Read time: 16; Write time: 115068 08:25:35 INFO - Running post-run listener: _upload_blobber_files 08:25:35 INFO - Blob upload gear active. 08:25:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:35 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 08:25:35 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:25:35 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:25:36 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:40 INFO - (blobuploader) - INFO - Done attempting. 08:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:40 INFO - (blobuploader) - INFO - Done attempting. 08:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:41 INFO - (blobuploader) - INFO - Done attempting. 08:25:41 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:41 INFO - Return code: 0 08:25:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c01f8145795cbe9452294566418c663c40210738df2a774f09ba6a2fbb41e3409e4fc7a858c0d6bc663a3afbec7f25163df08cf942edf7558a64bdc009e6403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e1ad2ab6b33ec55e37bece84a9ce60dc2f896273bf7478a9e783d3841d88cf1b1bfff1ab63b3b8ad032d5d129a1045da524ba3485fe1d01c3940ed39131742", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0e9b74cea81229ef5d4329557d16fb1c025d1fd5b78076987218ef1faeaa4356fbd3641516b42f47b83a8640342f11a9ba80de6804c7d0f472cf42ab3f84d4e"} 08:25:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:41 INFO - Contents: 08:25:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c01f8145795cbe9452294566418c663c40210738df2a774f09ba6a2fbb41e3409e4fc7a858c0d6bc663a3afbec7f25163df08cf942edf7558a64bdc009e6403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e1ad2ab6b33ec55e37bece84a9ce60dc2f896273bf7478a9e783d3841d88cf1b1bfff1ab63b3b8ad032d5d129a1045da524ba3485fe1d01c3940ed39131742", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0e9b74cea81229ef5d4329557d16fb1c025d1fd5b78076987218ef1faeaa4356fbd3641516b42f47b83a8640342f11a9ba80de6804c7d0f472cf42ab3f84d4e"} 08:25:41 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:41 INFO - Copying logs to upload dir... 08:25:41 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.960487 ========= master_lag: 0.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-13 08:25:42.402433) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 08:25:42.406256) ========= 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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c01f8145795cbe9452294566418c663c40210738df2a774f09ba6a2fbb41e3409e4fc7a858c0d6bc663a3afbec7f25163df08cf942edf7558a64bdc009e6403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e1ad2ab6b33ec55e37bece84a9ce60dc2f896273bf7478a9e783d3841d88cf1b1bfff1ab63b3b8ad032d5d129a1045da524ba3485fe1d01c3940ed39131742", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0e9b74cea81229ef5d4329557d16fb1c025d1fd5b78076987218ef1faeaa4356fbd3641516b42f47b83a8640342f11a9ba80de6804c7d0f472cf42ab3f84d4e"} build_url:https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010076 build_url: 'https://queue.taskcluster.net/v1/task/Wvm9rbUoTFWsLKiI2eCzFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c01f8145795cbe9452294566418c663c40210738df2a774f09ba6a2fbb41e3409e4fc7a858c0d6bc663a3afbec7f25163df08cf942edf7558a64bdc009e6403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e1ad2ab6b33ec55e37bece84a9ce60dc2f896273bf7478a9e783d3841d88cf1b1bfff1ab63b3b8ad032d5d129a1045da524ba3485fe1d01c3940ed39131742", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0e9b74cea81229ef5d4329557d16fb1c025d1fd5b78076987218ef1faeaa4356fbd3641516b42f47b83a8640342f11a9ba80de6804c7d0f472cf42ab3f84d4e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 08:25:42.430054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 08:25:42.430403) ========= 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=03627c79f6bb3d1578c4a402000001ba-1476370472.107996-591755750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 08:25:42.736311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 08:25:42.741705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 08:25:42.742062) ========= ========= Total master_lag: 1.14 =========