builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1477543685.46 results: success (0) buildid: 20161026185434 builduid: 7dffd3c580634f41a327d661d6ea4bb0 revision: 16e7406cee3df74471a05e6fe02f21f654c57bef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.458764) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.459293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.459644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.474515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.474877) ========= 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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-26 21:48:05-- 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% 89.4M=0s 2016-10-26 21:48:05 (89.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115748 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.605244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.605770) ========= 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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019516 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:05.655626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 21:48:05.656034) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 16e7406cee3df74471a05e6fe02f21f654c57bef --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 16e7406cee3df74471a05e6fe02f21f654c57bef --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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-26 21:48:05,648 truncating revision to first 12 chars 2016-10-26 21:48:05,648 Setting DEBUG logging. 2016-10-26 21:48:05,648 attempt 1/10 2016-10-26 21:48:05,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/16e7406cee3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 21:48:06,661 unpacking tar archive at: mozilla-beta-16e7406cee3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.411603 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 21:48:07.082272) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:07.082726) ========= script_repo_revision: 16e7406cee3df74471a05e6fe02f21f654c57bef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:07.083210) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:07.083610) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:48:07.093687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-26 21:48:07.094004) ========= /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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:48:07 INFO - MultiFileLogger online at 20161026 21:48:07 in /builds/slave/test 21:48:07 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 21:48:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:48:07 INFO - {'append_to_log': False, 21:48:07 INFO - 'base_work_dir': '/builds/slave/test', 21:48:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:48:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:48:07 INFO - 'branch': 'Mozilla-Beta', 21:48:07 INFO - 'buildbot_json_path': 'buildprops.json', 21:48:07 INFO - 'config_files': ('talos/linux_config.py',), 21:48:07 INFO - 'default_actions': ('clobber', 21:48:07 INFO - 'read-buildbot-config', 21:48:07 INFO - 'download-and-extract', 21:48:07 INFO - 'populate-webroot', 21:48:07 INFO - 'create-virtualenv', 21:48:07 INFO - 'install', 21:48:07 INFO - 'run-tests'), 21:48:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:48:07 INFO - 'download_minidump_stackwalk': True, 21:48:07 INFO - 'download_symbols': 'ondemand', 21:48:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:48:07 INFO - 'tooltool.py': '/tools/tooltool.py', 21:48:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:48:07 INFO - '/tools/misc-python/virtualenv.py')}, 21:48:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:48:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:48:07 INFO - 'installer_path': 'installer.exe', 21:48:07 INFO - 'log_level': 'info', 21:48:07 INFO - 'log_name': 'talos', 21:48:07 INFO - 'log_to_console': True, 21:48:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:48:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:48:07 INFO - 'opt_config_files': (), 21:48:07 INFO - 'pip_index': False, 21:48:07 INFO - 'sps_profile': False, 21:48:07 INFO - 'sps_profile_interval': 0, 21:48:07 INFO - 'suite': 'dromaeojs', 21:48:07 INFO - 'system_bits': '32', 21:48:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:48:07 INFO - 'title': 'talos-linux64-ix-031', 21:48:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:48:07 INFO - 'use_talos_json': True, 21:48:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:48:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:48:07 INFO - 'work_dir': 'build'} 21:48:07 INFO - [mozharness: 2016-10-27 04:48:07.184542Z] Running clobber step. 21:48:07 INFO - Running pre-action listener: _resource_record_pre_action 21:48:07 INFO - Running main action method: clobber 21:48:07 INFO - rmtree: /builds/slave/test/build 21:48:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:48:07 INFO - Running post-action listener: _resource_record_post_action 21:48:07 INFO - [mozharness: 2016-10-27 04:48:07.642664Z] Finished clobber step (success) 21:48:07 INFO - [mozharness: 2016-10-27 04:48:07.642765Z] Running read-buildbot-config step. 21:48:07 INFO - Running pre-action listener: _resource_record_pre_action 21:48:07 INFO - Running main action method: read_buildbot_config 21:48:07 INFO - Using buildbot properties: 21:48:07 INFO - { 21:48:07 INFO - "project": "", 21:48:07 INFO - "product": "firefox", 21:48:07 INFO - "script_repo_revision": "production", 21:48:07 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 21:48:07 INFO - "repository": "", 21:48:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 21:48:07 INFO - "buildid": "20161026185434", 21:48:07 INFO - "pgo_build": "True", 21:48:07 INFO - "basedir": "/builds/slave/test", 21:48:07 INFO - "buildnumber": 285, 21:48:07 INFO - "slavename": "talos-linux64-ix-031", 21:48:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:48:07 INFO - "platform": "ubuntu64_hw", 21:48:07 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:48:07 INFO - "branch": "mozilla-beta", 21:48:07 INFO - "repo_path": "releases/mozilla-beta", 21:48:07 INFO - "revision": "16e7406cee3df74471a05e6fe02f21f654c57bef", 21:48:07 INFO - "stage_platform": "linux64-pgo", 21:48:07 INFO - "builduid": "7dffd3c580634f41a327d661d6ea4bb0", 21:48:07 INFO - "slavebuilddir": "test" 21:48:07 INFO - } 21:48:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 21:48:07 INFO - Running post-action listener: _resource_record_post_action 21:48:07 INFO - [mozharness: 2016-10-27 04:48:07.645205Z] Finished read-buildbot-config step (success) 21:48:07 INFO - [mozharness: 2016-10-27 04:48:07.645305Z] Running download-and-extract step. 21:48:07 INFO - Running pre-action listener: _resource_record_pre_action 21:48:07 INFO - Running main action method: download_and_extract 21:48:07 INFO - mkdir: /builds/slave/test/build/tests 21:48:07 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')]} 21:48:07 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:48:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:48:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:48:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:48:07 INFO - retry: Failed, sleeping 30 seconds before retrying 21:48:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:48:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:48:37 INFO - retry: Failed, sleeping 60 seconds before retrying 21:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:49:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:49:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:49:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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! 21:49:37 INFO - Caught exception: HTTP Error 404: Not Found 21:49:37 INFO - Caught exception: HTTP Error 404: Not Found 21:49:37 INFO - Caught exception: HTTP Error 404: Not Found 21:49:37 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:49:37 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:49:38 INFO - Downloaded 1327 bytes. 21:49:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:49:38 INFO - Using the following test package requirements: 21:49:38 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:49:38 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:49:38 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 21:49:38 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'jsshell-linux-x86_64.zip'], 21:49:38 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:49:38 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:49:38 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 21:49:38 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 21:49:38 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:49:38 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:49:38 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:49:38 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:49:38 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')]} 21:49:38 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:49:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:49:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:49:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:49:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:49:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:49:38 INFO - retry: Failed, sleeping 30 seconds before retrying 21:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:50:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:50:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:50:08 INFO - retry: Failed, sleeping 60 seconds before retrying 21:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:51:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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! 21:51:08 INFO - Caught exception: HTTP Error 404: Not Found 21:51:08 INFO - Caught exception: HTTP Error 404: Not Found 21:51:08 INFO - Caught exception: HTTP Error 404: Not Found 21:51:08 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:08 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:09 INFO - Downloaded 21788698 bytes. 21:51:09 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'] 21:51:09 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 21:51:10 INFO - Return code: 0 21:51:10 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 21:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:51:10 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:51:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:51:10 INFO - retry: Failed, sleeping 30 seconds before retrying 21:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:51:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:51:40 INFO - retry: Failed, sleeping 60 seconds before retrying 21:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:52:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:52:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:52:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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! 21:52:40 INFO - Caught exception: HTTP Error 404: Not Found 21:52:40 INFO - Caught exception: HTTP Error 404: Not Found 21:52:40 INFO - Caught exception: HTTP Error 404: Not Found 21:52:40 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:52:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:52:41 INFO - Downloaded 21788698 bytes. 21:52:41 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'] 21:52:41 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 21:52:42 INFO - Return code: 0 21:52:42 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')]} 21:52:42 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:52:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:52:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:52:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:52:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:52:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:52:42 INFO - retry: Failed, sleeping 30 seconds before retrying 21:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:53:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:53:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:53:12 INFO - retry: Failed, sleeping 60 seconds before retrying 21:54:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:54:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:54:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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! 21:54:12 INFO - Caught exception: HTTP Error 404: Not Found 21:54:12 INFO - Caught exception: HTTP Error 404: Not Found 21:54:12 INFO - Caught exception: HTTP Error 404: Not Found 21:54:12 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:54:12 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:13 INFO - Downloaded 14413762 bytes. 21:54:13 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'] 21:54:13 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 21:54:13 INFO - Return code: 0 21:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:13 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:54:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:54:13 INFO - retry: Failed, sleeping 30 seconds before retrying 21:54:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:54:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:54:43 INFO - retry: Failed, sleeping 60 seconds before retrying 21:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:55:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:55:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:55:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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! 21:55:44 INFO - Caught exception: HTTP Error 404: Not Found 21:55:44 INFO - Caught exception: HTTP Error 404: Not Found 21:55:44 INFO - Caught exception: HTTP Error 404: Not Found 21:55:44 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:55:44 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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 21:55:45 INFO - Downloaded 56798519 bytes. 21:55:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:55:45 INFO - mkdir: /builds/slave/test/properties 21:55:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:55:45 INFO - Writing to file /builds/slave/test/properties/build_url 21:55:45 INFO - Contents: 21:55:45 INFO - build_url:https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:55:46 INFO - Running post-action listener: _resource_record_post_action 21:55:46 INFO - Running post-action listener: set_extra_try_arguments 21:55:46 INFO - [mozharness: 2016-10-27 04:55:46.446631Z] Finished download-and-extract step (success) 21:55:46 INFO - [mozharness: 2016-10-27 04:55:46.446863Z] Running populate-webroot step. 21:55:46 INFO - Running pre-action listener: _resource_record_pre_action 21:55:46 INFO - Running main action method: populate_webroot 21:55:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:46 INFO - '--tppagecycles', 21:55:46 INFO - '7'], 21:55:46 INFO - 'tests': ['tsvgm']}}, 21:55:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:55:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:55:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:55:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['damp', 'tps']}, 21:55:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'tests': ['damp', 'tps']}, 21:55:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['dromaeo_dom']}, 21:55:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['basic_compositor_video']}, 21:55:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:55:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'other-e10s': {'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['a11yr', 21:55:46 INFO - 'ts_paint', 21:55:46 INFO - 'tpaint', 21:55:46 INFO - 'sessionrestore', 21:55:46 INFO - 'sessionrestore_no_auto_restore', 21:55:46 INFO - 'tabpaint']}, 21:55:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['tsvgx', 21:55:46 INFO - 'tsvgr_opacity', 21:55:46 INFO - 'tart', 21:55:46 INFO - 'tscrollx', 21:55:46 INFO - 'cart']}, 21:55:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:55:46 INFO - 'tsvgr_opacity', 21:55:46 INFO - 'tart', 21:55:46 INFO - 'tscrollx', 21:55:46 INFO - 'cart']}, 21:55:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'talos_options': ['--disable-e10s'], 21:55:46 INFO - 'tests': ['tp5o']}, 21:55:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'tests': ['tp5o']}, 21:55:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'talos_options': ['--disable-e10s', 21:55:46 INFO - '--xperf_path', 21:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:46 INFO - 'tests': ['tp5n']}, 21:55:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:46 INFO - 'talos_options': ['--xperf_path', 21:55:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:46 INFO - 'tests': ['tp5n']}}, 21:55:46 INFO - 'talos.zip': {'path': '', 21:55:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:46 INFO - Running post-action listener: _resource_record_post_action 21:55:46 INFO - [mozharness: 2016-10-27 04:55:46.469320Z] Finished populate-webroot step (success) 21:55:46 INFO - [mozharness: 2016-10-27 04:55:46.469411Z] Running create-virtualenv step. 21:55:46 INFO - Running pre-action listener: _resource_record_pre_action 21:55:46 INFO - Running main action method: create_virtualenv 21:55:46 INFO - Creating virtualenv /builds/slave/test/build/venv 21:55:46 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 21:55:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:55:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:46 INFO - Using real prefix '/usr' 21:55:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:55:47 INFO - Installing distribute.............................................................................................................................................................................................done. 21:55:49 INFO - Installing pip.................done. 21:55:49 INFO - Return code: 0 21:55:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:55: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')]} 21:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:49 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:49 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 21:55:49 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 21:55:49 INFO - Using env: {'DISPLAY': ':0', 21:55:49 INFO - 'HOME': '/home/cltbld', 21:55:49 INFO - 'LANG': 'en_US.UTF-8', 21:55:49 INFO - 'LANGUAGE': 'en_US:en', 21:55:49 INFO - 'LOGNAME': 'cltbld', 21:55:49 INFO - 'MAIL': '/var/mail/cltbld', 21:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:49 INFO - 'MOZ_NO_REMOTE': '1', 21:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:49 INFO - 'NO_EM_RESTART': '1', 21:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:49 INFO - 'PWD': '/builds/slave/test', 21:55:49 INFO - 'SHELL': '/bin/bash', 21:55:49 INFO - 'SHLVL': '1', 21:55:49 INFO - 'TERM': 'linux', 21:55:49 INFO - 'TMOUT': '86400', 21:55:49 INFO - 'USER': 'cltbld', 21:55:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:49 INFO - '_': '/tools/buildbot/bin/python'} 21:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:49 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 21:55:49 INFO - Cleaning up... 21:55:49 INFO - Return code: 0 21:55:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:55: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')]} 21:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:49 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:49 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 21:55:49 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 21:55:49 INFO - Using env: {'DISPLAY': ':0', 21:55:49 INFO - 'HOME': '/home/cltbld', 21:55:49 INFO - 'LANG': 'en_US.UTF-8', 21:55:49 INFO - 'LANGUAGE': 'en_US:en', 21:55:49 INFO - 'LOGNAME': 'cltbld', 21:55:49 INFO - 'MAIL': '/var/mail/cltbld', 21:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:49 INFO - 'MOZ_NO_REMOTE': '1', 21:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:49 INFO - 'NO_EM_RESTART': '1', 21:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:49 INFO - 'PWD': '/builds/slave/test', 21:55:49 INFO - 'SHELL': '/bin/bash', 21:55:49 INFO - 'SHLVL': '1', 21:55:49 INFO - 'TERM': 'linux', 21:55:49 INFO - 'TMOUT': '86400', 21:55:49 INFO - 'USER': 'cltbld', 21:55:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:49 INFO - '_': '/tools/buildbot/bin/python'} 21:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:49 INFO - Downloading/unpacking psutil>=3.1.1 21:55:49 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 21:55:49 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 21:55:49 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 21:55:49 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 21:55:49 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 21:55:49 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 21:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:51 INFO - Installing collected packages: psutil 21:55:51 INFO - Running setup.py install for psutil 21:55:51 INFO - building 'psutil._psutil_linux' extension 21:55:51 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 21:55:51 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 21:55:51 INFO - building 'psutil._psutil_posix' extension 21:55:51 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 21:55:51 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 21:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:51 INFO - Successfully installed psutil 21:55:51 INFO - Cleaning up... 21:55:52 INFO - Return code: 0 21:55:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:55:52 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')]} 21:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:52 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')]} 21:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:52 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:52 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 21:55:52 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 21:55:52 INFO - Using env: {'DISPLAY': ':0', 21:55:52 INFO - 'HOME': '/home/cltbld', 21:55:52 INFO - 'LANG': 'en_US.UTF-8', 21:55:52 INFO - 'LANGUAGE': 'en_US:en', 21:55:52 INFO - 'LOGNAME': 'cltbld', 21:55:52 INFO - 'MAIL': '/var/mail/cltbld', 21:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:52 INFO - 'MOZ_NO_REMOTE': '1', 21:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:52 INFO - 'NO_EM_RESTART': '1', 21:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:52 INFO - 'PWD': '/builds/slave/test', 21:55:52 INFO - 'SHELL': '/bin/bash', 21:55:52 INFO - 'SHLVL': '1', 21:55:52 INFO - 'TERM': 'linux', 21:55:52 INFO - 'TMOUT': '86400', 21:55:52 INFO - 'USER': 'cltbld', 21:55:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:52 INFO - '_': '/tools/buildbot/bin/python'} 21:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:55:52 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 21:55:52 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 21:55:52 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 21:55:52 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 21:55:52 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 21:55:52 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 21:55:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:55:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:55:54 INFO - Installing collected packages: mozsystemmonitor 21:55:54 INFO - Running setup.py install for mozsystemmonitor 21:55:54 INFO - Successfully installed mozsystemmonitor 21:55:54 INFO - Cleaning up... 21:55:54 INFO - Return code: 0 21:55:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:55:54 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')]} 21:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:54 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')]} 21:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:54 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:54 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 21:55:54 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 21:55:54 INFO - Using env: {'DISPLAY': ':0', 21:55:54 INFO - 'HOME': '/home/cltbld', 21:55:54 INFO - 'LANG': 'en_US.UTF-8', 21:55:54 INFO - 'LANGUAGE': 'en_US:en', 21:55:54 INFO - 'LOGNAME': 'cltbld', 21:55:54 INFO - 'MAIL': '/var/mail/cltbld', 21:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:54 INFO - 'MOZ_NO_REMOTE': '1', 21:55:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:54 INFO - 'NO_EM_RESTART': '1', 21:55:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:54 INFO - 'PWD': '/builds/slave/test', 21:55:54 INFO - 'SHELL': '/bin/bash', 21:55:54 INFO - 'SHLVL': '1', 21:55:54 INFO - 'TERM': 'linux', 21:55:54 INFO - 'TMOUT': '86400', 21:55:54 INFO - 'USER': 'cltbld', 21:55:54 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:54 INFO - '_': '/tools/buildbot/bin/python'} 21:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:54 INFO - Downloading/unpacking jsonschema==2.5.1 21:55:54 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 21:55:54 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 21:55:54 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 21:55:54 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 21:55:54 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 21:55:54 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 21:55:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:55:57 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 21:55:57 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 21:55:57 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 21:55:57 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 21:55:57 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 21:55:57 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 21:55:57 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:57 INFO - warning: no files found matching '*.txt' 21:55:57 INFO - no previously-included directories found matching 'build' 21:55:57 INFO - no previously-included directories found matching 'dist' 21:55:57 INFO - no previously-included directories found matching '.git*' 21:55:57 INFO - Installing collected packages: jsonschema, functools32 21:55:57 INFO - Running setup.py install for functools32 21:55:57 INFO - warning: no files found matching '*.txt' 21:55:57 INFO - no previously-included directories found matching 'build' 21:55:57 INFO - no previously-included directories found matching 'dist' 21:55:57 INFO - no previously-included directories found matching '.git*' 21:55:57 INFO - Successfully installed jsonschema functools32 21:55:57 INFO - Cleaning up... 21:55:57 INFO - Return code: 0 21:55:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:55:57 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')]} 21:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:57 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')]} 21:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:57 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:57 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 21:55:57 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 21:55:57 INFO - Using env: {'DISPLAY': ':0', 21:55:57 INFO - 'HOME': '/home/cltbld', 21:55:57 INFO - 'LANG': 'en_US.UTF-8', 21:55:57 INFO - 'LANGUAGE': 'en_US:en', 21:55:57 INFO - 'LOGNAME': 'cltbld', 21:55:57 INFO - 'MAIL': '/var/mail/cltbld', 21:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:57 INFO - 'MOZ_NO_REMOTE': '1', 21:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:57 INFO - 'NO_EM_RESTART': '1', 21:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:57 INFO - 'PWD': '/builds/slave/test', 21:55:57 INFO - 'SHELL': '/bin/bash', 21:55:57 INFO - 'SHLVL': '1', 21:55:57 INFO - 'TERM': 'linux', 21:55:57 INFO - 'TMOUT': '86400', 21:55:57 INFO - 'USER': 'cltbld', 21:55:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:57 INFO - '_': '/tools/buildbot/bin/python'} 21:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:55:58 INFO - Cleaning up... 21:55:58 INFO - Return code: 0 21:55:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:55:58 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')]} 21:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:58 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')]} 21:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:58 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:55:58 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 21:55:58 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 21:55:58 INFO - Using env: {'DISPLAY': ':0', 21:55:58 INFO - 'HOME': '/home/cltbld', 21:55:58 INFO - 'LANG': 'en_US.UTF-8', 21:55:58 INFO - 'LANGUAGE': 'en_US:en', 21:55:58 INFO - 'LOGNAME': 'cltbld', 21:55:58 INFO - 'MAIL': '/var/mail/cltbld', 21:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:58 INFO - 'MOZ_NO_REMOTE': '1', 21:55:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:58 INFO - 'NO_EM_RESTART': '1', 21:55:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:58 INFO - 'PWD': '/builds/slave/test', 21:55:58 INFO - 'SHELL': '/bin/bash', 21:55:58 INFO - 'SHLVL': '1', 21:55:58 INFO - 'TERM': 'linux', 21:55:58 INFO - 'TMOUT': '86400', 21:55:58 INFO - 'USER': 'cltbld', 21:55:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:58 INFO - '_': '/tools/buildbot/bin/python'} 21:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:58 INFO - Downloading/unpacking blobuploader==1.2.4 21:55:58 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 21:55:58 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 21:55:58 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 21:55:58 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 21:55:58 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 21:55:58 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 21:56:00 INFO - Downloading blobuploader-1.2.4.tar.gz 21:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:56:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:56:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:00 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 21:56:01 INFO - Downloading docopt-0.6.1.tar.gz 21:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:56:01 INFO - Installing collected packages: blobuploader, requests, docopt 21:56:01 INFO - Running setup.py install for blobuploader 21:56:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:56:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:56:01 INFO - Running setup.py install for requests 21:56:01 INFO - Running setup.py install for docopt 21:56:01 INFO - Successfully installed blobuploader requests docopt 21:56:01 INFO - Cleaning up... 21:56:01 INFO - Return code: 0 21:56:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:01 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')]} 21:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:01 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')]} 21:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:01 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:56:01 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 21:56:01 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 21:56:01 INFO - Using env: {'DISPLAY': ':0', 21:56:01 INFO - 'HOME': '/home/cltbld', 21:56:01 INFO - 'LANG': 'en_US.UTF-8', 21:56:01 INFO - 'LANGUAGE': 'en_US:en', 21:56:01 INFO - 'LOGNAME': 'cltbld', 21:56:01 INFO - 'MAIL': '/var/mail/cltbld', 21:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:01 INFO - 'MOZ_NO_REMOTE': '1', 21:56:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:01 INFO - 'NO_EM_RESTART': '1', 21:56:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:01 INFO - 'PWD': '/builds/slave/test', 21:56:01 INFO - 'SHELL': '/bin/bash', 21:56:01 INFO - 'SHLVL': '1', 21:56:01 INFO - 'TERM': 'linux', 21:56:01 INFO - 'TMOUT': '86400', 21:56:01 INFO - 'USER': 'cltbld', 21:56:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:01 INFO - '_': '/tools/buildbot/bin/python'} 21:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:01 INFO - Running setup.py (path:/tmp/pip-7EqKRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:01 INFO - Running setup.py (path:/tmp/pip-JvE8_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:02 INFO - Running setup.py (path:/tmp/pip-DMF29N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:02 INFO - Running setup.py (path:/tmp/pip-nwFBOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:02 INFO - Running setup.py (path:/tmp/pip-21Osvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:02 INFO - Running setup.py (path:/tmp/pip-d9ETfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:02 INFO - Running setup.py (path:/tmp/pip-i8MTJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:02 INFO - Running setup.py (path:/tmp/pip-CbAUzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:02 INFO - Running setup.py (path:/tmp/pip-ZqHcpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:02 INFO - Running setup.py (path:/tmp/pip-Fy6ZXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:02 INFO - Running setup.py (path:/tmp/pip-DbG3FE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:02 INFO - Running setup.py (path:/tmp/pip-VxrdOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:02 INFO - Running setup.py (path:/tmp/pip-XSz2zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:02 INFO - Running setup.py (path:/tmp/pip-AjDVgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:02 INFO - Running setup.py (path:/tmp/pip-CUpgnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:02 INFO - Running setup.py (path:/tmp/pip-JE110Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:03 INFO - Running setup.py (path:/tmp/pip-7kij3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:56:03 INFO - Running setup.py install for manifestparser 21:56:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozcrash 21:56:03 INFO - Running setup.py install for mozdebug 21:56:03 INFO - Running setup.py install for mozdevice 21:56:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozfile 21:56:03 INFO - Running setup.py install for mozhttpd 21:56:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozinfo 21:56:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozInstall 21:56:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:56:03 INFO - Running setup.py install for mozleak 21:56:04 INFO - Running setup.py install for mozlog 21:56:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for moznetwork 21:56:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for mozprocess 21:56:04 INFO - Running setup.py install for mozprofile 21:56:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for mozrunner 21:56:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Running setup.py install for mozscreenshot 21:56:04 INFO - Running setup.py install for moztest 21:56:04 INFO - Running setup.py install for mozversion 21:56:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:56:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:56:04 INFO - Cleaning up... 21:56:04 INFO - Return code: 0 21:56:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:04 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:56:04 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 21:56:04 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 21:56:04 INFO - Using env: {'DISPLAY': ':0', 21:56:04 INFO - 'HOME': '/home/cltbld', 21:56:04 INFO - 'LANG': 'en_US.UTF-8', 21:56:04 INFO - 'LANGUAGE': 'en_US:en', 21:56:04 INFO - 'LOGNAME': 'cltbld', 21:56:04 INFO - 'MAIL': '/var/mail/cltbld', 21:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:04 INFO - 'MOZ_NO_REMOTE': '1', 21:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:04 INFO - 'NO_EM_RESTART': '1', 21:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:04 INFO - 'PWD': '/builds/slave/test', 21:56:04 INFO - 'SHELL': '/bin/bash', 21:56:04 INFO - 'SHLVL': '1', 21:56:04 INFO - 'TERM': 'linux', 21:56:04 INFO - 'TMOUT': '86400', 21:56:04 INFO - 'USER': 'cltbld', 21:56:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:04 INFO - '_': '/tools/buildbot/bin/python'} 21:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:05 INFO - Running setup.py (path:/tmp/pip-Mut5Y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:05 INFO - Running setup.py (path:/tmp/pip-uV4B2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:05 INFO - Running setup.py (path:/tmp/pip-gDgPW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:05 INFO - Running setup.py (path:/tmp/pip-E3RErw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:05 INFO - Running setup.py (path:/tmp/pip-LU8kUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:05 INFO - Running setup.py (path:/tmp/pip-8AJQfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:05 INFO - Running setup.py (path:/tmp/pip-kNKz91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:05 INFO - Running setup.py (path:/tmp/pip-2TXKbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:05 INFO - Running setup.py (path:/tmp/pip-Q0UdMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:05 INFO - Running setup.py (path:/tmp/pip-Xktm9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:05 INFO - Running setup.py (path:/tmp/pip-mQnTYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:05 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)) 21:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:05 INFO - Running setup.py (path:/tmp/pip-dUQZs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:06 INFO - Running setup.py (path:/tmp/pip-w7WqAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:06 INFO - Running setup.py (path:/tmp/pip-1N4Nbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:06 INFO - Running setup.py (path:/tmp/pip-bXGC5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:06 INFO - Running setup.py (path:/tmp/pip-2VDFjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:06 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)) 21:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:06 INFO - Running setup.py (path:/tmp/pip-1CiOCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:06 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)) 21:56:06 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)) 21:56:06 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)) 21:56:06 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)) 21:56:06 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)) 21:56:06 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)) 21:56:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:08 INFO - Downloading blessings-1.6.tar.gz 21:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:56:08 INFO - Installing collected packages: blessings 21:56:08 INFO - Running setup.py install for blessings 21:56:08 INFO - Successfully installed blessings 21:56:08 INFO - Cleaning up... 21:56:08 INFO - Return code: 0 21:56:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:08 INFO - Reading from file tmpfile_stdout 21:56:08 INFO - Current package versions: 21:56:08 INFO - argparse == 1.2.1 21:56:08 INFO - blessings == 1.6 21:56:08 INFO - blobuploader == 1.2.4 21:56:08 INFO - docopt == 0.6.1 21:56:08 INFO - functools32 == 3.2.3-2 21:56:08 INFO - jsonschema == 2.5.1 21:56:08 INFO - manifestparser == 1.1 21:56:08 INFO - mozInstall == 1.12 21:56:08 INFO - mozcrash == 0.17 21:56:08 INFO - mozdebug == 0.1 21:56:08 INFO - mozdevice == 0.48 21:56:08 INFO - mozfile == 1.2 21:56:08 INFO - mozhttpd == 0.7 21:56:08 INFO - mozinfo == 0.9 21:56:08 INFO - mozleak == 0.1 21:56:08 INFO - mozlog == 3.3 21:56:08 INFO - moznetwork == 0.27 21:56:08 INFO - mozprocess == 0.23 21:56:08 INFO - mozprofile == 0.28 21:56:08 INFO - mozrunner == 6.12 21:56:08 INFO - mozscreenshot == 0.1 21:56:08 INFO - mozsystemmonitor == 0.3 21:56:08 INFO - moztest == 0.7 21:56:08 INFO - mozversion == 1.4 21:56:08 INFO - psutil == 3.1.1 21:56:08 INFO - requests == 1.2.3 21:56:08 INFO - wsgiref == 0.1.2 21:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:08 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')]} 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:08 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')]} 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:08 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:56:08 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 21:56:08 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 21:56:08 INFO - Using env: {'DISPLAY': ':0', 21:56:08 INFO - 'HOME': '/home/cltbld', 21:56:08 INFO - 'LANG': 'en_US.UTF-8', 21:56:08 INFO - 'LANGUAGE': 'en_US:en', 21:56:08 INFO - 'LOGNAME': 'cltbld', 21:56:08 INFO - 'MAIL': '/var/mail/cltbld', 21:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:08 INFO - 'MOZ_NO_REMOTE': '1', 21:56:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:08 INFO - 'NO_EM_RESTART': '1', 21:56:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:08 INFO - 'PWD': '/builds/slave/test', 21:56:08 INFO - 'SHELL': '/bin/bash', 21:56:08 INFO - 'SHLVL': '1', 21:56:08 INFO - 'TERM': 'linux', 21:56:08 INFO - 'TMOUT': '86400', 21:56:08 INFO - 'USER': 'cltbld', 21:56:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:08 INFO - '_': '/tools/buildbot/bin/python'} 21:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 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)) 21:56:08 INFO - Cleaning up... 21:56:08 INFO - Return code: 0 21:56:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:56:08 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')]} 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:08 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')]} 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:08 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 0x213a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, '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': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', '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 21:56:08 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 21:56:08 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 21:56:08 INFO - Using env: {'DISPLAY': ':0', 21:56:08 INFO - 'HOME': '/home/cltbld', 21:56:08 INFO - 'LANG': 'en_US.UTF-8', 21:56:08 INFO - 'LANGUAGE': 'en_US:en', 21:56:08 INFO - 'LOGNAME': 'cltbld', 21:56:08 INFO - 'MAIL': '/var/mail/cltbld', 21:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:08 INFO - 'MOZ_NO_REMOTE': '1', 21:56:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:08 INFO - 'NO_EM_RESTART': '1', 21:56:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:08 INFO - 'PWD': '/builds/slave/test', 21:56:08 INFO - 'SHELL': '/bin/bash', 21:56:08 INFO - 'SHLVL': '1', 21:56:08 INFO - 'TERM': 'linux', 21:56:08 INFO - 'TMOUT': '86400', 21:56:08 INFO - 'USER': 'cltbld', 21:56:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:08 INFO - '_': '/tools/buildbot/bin/python'} 21:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:56:09 INFO - Cleaning up... 21:56:09 INFO - Return code: 0 21:56:09 INFO - Running post-action listener: _resource_record_post_action 21:56:09 INFO - Running post-action listener: _start_resource_monitoring 21:56:09 INFO - Starting resource monitoring. 21:56:09 INFO - [mozharness: 2016-10-27 04:56:09.228796Z] Finished create-virtualenv step (success) 21:56:09 INFO - [mozharness: 2016-10-27 04:56:09.229220Z] Running install step. 21:56:09 INFO - Running pre-action listener: _resource_record_pre_action 21:56:09 INFO - Running main action method: install 21:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:09 INFO - Reading from file tmpfile_stdout 21:56:09 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:56:09 INFO - Reading from file tmpfile_stdout 21:56:09 INFO - Output received: 21:56:09 INFO - Usage: mozinstall [options] installer 21:56:09 INFO - Options: 21:56:09 INFO - -h, --help show this help message and exit 21:56:09 INFO - -d DEST, --destination=DEST 21:56:09 INFO - Directory to install application into. [default: 21:56:09 INFO - "/builds/slave/test"] 21:56:09 INFO - --app=APP Application being installed. [default: firefox] 21:56:09 INFO - mkdir: /builds/slave/test/build/application 21:56:09 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'] 21:56:09 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 21:56:23 INFO - Reading from file tmpfile_stdout 21:56:23 INFO - Output received: 21:56:23 INFO - /builds/slave/test/build/application/firefox/firefox 21:56:23 INFO - Running post-action listener: _resource_record_post_action 21:56:23 INFO - [mozharness: 2016-10-27 04:56:23.541607Z] Finished install step (success) 21:56:23 INFO - [mozharness: 2016-10-27 04:56:23.541887Z] Running run-tests step. 21:56:23 INFO - Running pre-action listener: _resource_record_pre_action 21:56:23 INFO - Running main action method: run_tests 21:56:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:56:23 INFO - Python 2.7.3 21:56:23 INFO - Return code: 0 21:56:23 INFO - grabbing minidump binary from tooltool 21:56:23 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')]} 21:56:23 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 0x23f93b0>, '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 0x23fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23fa740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:56:23 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 21:56:23 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 21:56:23 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 21:56:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:56:23 INFO - Return code: 0 21:56:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:56:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:56:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:56:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:56:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:56:23 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/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 21:56:23 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/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 21:56:23 INFO - Using env: {'DISPLAY': ':0', 21:56:23 INFO - 'HOME': '/home/cltbld', 21:56:23 INFO - 'LANG': 'en_US.UTF-8', 21:56:23 INFO - 'LANGUAGE': 'en_US:en', 21:56:23 INFO - 'LOGNAME': 'cltbld', 21:56:23 INFO - 'MAIL': '/var/mail/cltbld', 21:56:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:56:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:23 INFO - 'MOZ_NO_REMOTE': '1', 21:56:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:23 INFO - 'NO_EM_RESTART': '1', 21:56:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:23 INFO - 'PWD': '/builds/slave/test', 21:56:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:56:23 INFO - 'SHELL': '/bin/bash', 21:56:23 INFO - 'SHLVL': '1', 21:56:23 INFO - 'TERM': 'linux', 21:56:23 INFO - 'TMOUT': '86400', 21:56:23 INFO - 'USER': 'cltbld', 21:56:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003', 21:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:23 INFO - '_': '/tools/buildbot/bin/python'} 21:56:23 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/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 21:56:23 INFO - mozversion application_buildid: 20161026185434 21:56:23 INFO - mozversion application_changeset: 16e7406cee3df74471a05e6fe02f21f654c57bef 21:56:23 INFO - mozversion application_display_name: Firefox 21:56:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:56:23 INFO - mozversion application_name: Firefox 21:56:23 INFO - mozversion application_remotingname: firefox 21:56:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:56:23 INFO - mozversion application_vendor: Mozilla 21:56:23 INFO - mozversion application_version: 50.0 21:56:23 INFO - mozversion platform_buildid: 20161026185434 21:56:23 INFO - mozversion platform_changeset: 16e7406cee3df74471a05e6fe02f21f654c57bef 21:56:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:56:23 INFO - mozversion platform_version: 50.0 21:56:23 INFO - using testdate: 1477544183 21:56:23 INFO - actual date: 1477544183 21:56:23 INFO - starting webserver on 'localhost:56006' 21:56:23 INFO - SUITE-START | Running 2 tests 21:56:23 INFO - TEST-START | dromaeo_css 21:56:23 INFO - operating with platform_type : linux_ 21:56:23 INFO - Initialising browser for dromaeo_css test... 21:56:23 INFO - TEST-INFO | started process 13119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0uFyAB/profile http://localhost:56006/getInfo.html) 21:56:27 INFO - PROCESS | 13119 | __metrics Screen width/height:1600/1200 21:56:27 INFO - PROCESS | 13119 | colorDepth:24 21:56:27 INFO - PROCESS | 13119 | Browser inner width/height: 1024/697 21:56:27 INFO - PROCESS | 13119 | __metrics 21:56:28 INFO - PROCESS | 13119 | 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" 21:56:28 INFO - PROCESS | 13119 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:56:28 INFO - PROCESS | 13119 | console.error: PushService: 21:56:28 INFO - PROCESS | 13119 | stateChangeProcessEnqueue: Error transitioning state 21:56:28 INFO - PROCESS | 13119 | UnknownError 21:56:29 INFO - TEST-INFO | 13119: exit 0 21:56:29 INFO - Browser initialized. 21:56:29 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:29 INFO - TEST-INFO | started process 13209 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0uFyAB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:40 INFO - PROCESS | 13209 | RSS: Main: 143765504 21:56:40 INFO - PROCESS | 13209 | 21:57:32 INFO - PROCESS | 13209 | 1477544252587 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] 21:59:40 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56006/tests/dromaeo/cssquery-ext.html) 21:59:41 INFO - PROCESS | 13209 | RSS: Main: 271196160 21:59:41 INFO - PROCESS | 13209 | 22:02:43 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-ext.html (next: http://localhost:56006/tests/dromaeo/cssquery-jquery.html) 22:02:44 INFO - PROCESS | 13209 | RSS: Main: 231346176 22:02:44 INFO - PROCESS | 13209 | 22:05:46 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56006/tests/dromaeo/cssquery-mootools.html) 22:05:46 INFO - PROCESS | 13209 | RSS: Main: 176218112 22:05:46 INFO - PROCESS | 13209 | 22:08:48 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56006/tests/dromaeo/cssquery-prototype.html) 22:08:49 INFO - PROCESS | 13209 | RSS: Main: 176377856 22:08:49 INFO - PROCESS | 13209 | 22:11:51 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56006/tests/dromaeo/cssquery-yui.html) 22:11:52 INFO - PROCESS | 13209 | RSS: Main: 177823744 22:11:52 INFO - PROCESS | 13209 | 22:14:54 INFO - PROCESS | 13209 | Cycle 1(1): loaded http://localhost:56006/tests/dromaeo/cssquery-yui.html (next: http://localhost:56006/tests/dromaeo/cssquery-dojo.html) 22:14:55 INFO - PROCESS | 13209 | RSS: Main: 266899456 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | __start_tp_report 22:14:55 INFO - PROCESS | 13209 | _x_x_mozilla_page_load 22:14:55 INFO - PROCESS | 13209 | _x_x_mozilla_page_load_details 22:14:55 INFO - PROCESS | 13209 | |i|pagename|runs| 22:14:55 INFO - PROCESS | 13209 | |0;dojo.html;3159.6840315968398;3185.251465905512;3204.8718051277933;3227.661095584965;3255.690709382608;5559.972200138999;5649.463300986405;5653.717314134292;5665.688387138705;5666.461686139821;5648.350439699436;5695.0887857942735;5699.344575373833;5710.029295019846;5722.078982210583;5144.408393034792;5148.459411761758;5165.870853228681;5199.714015729133;5222.216667499867;5011.223260394627;5206.817761378353;5229.581633469333;5233.188855727369;5262.421133675292;4967.776450059738;5047.621428392867;5052.570531504826;5055.165897626887;5096.79612815487;5072.137736584789;5087.236914462842;5121.718305493197;5197.7401129943355;5197.870053248661;4995.175795993657;5093.286939828427;5154.948450515484;5164.586833053347;5194.558462530689;5436.184572314145;5448.128299472065;5473.972630136864;5493.258410114623;5592.5805564582415;5912.733926973297;5926.940730592682;5966.403359664042;6019.428154325332;6020.157177995085;5793.420657934215;6021.36775638556;6055.545834062463;6068.878622427571;6070.787522436738;5499.945000549983;5515;5536.806211782568;5544.69504177266;5573.8606534836545;6100.023996160593;6123.2652081750475;6139.416755408229;6139.877202455926;6146.262448506119;5692;5857.531397488191;5879.941200588025;5882.44116808911;5896.056630939029;5937.050071988548;6044.395560443921;6052.485538729169;6054.485368743618;6059.8485037873525;5974.641521508636;5990.850228744316;6000.429959153961;6003.279606447254;6003.7898673546215;5649.378568357477;5890.970545147246;6027.819165425044;6030.125631783367;6040.244969378828;4572.977135114304;4596.724196548218;4598.701084429501;4623.768811559408;4625.375574297427;4074.6740260779065;4108.794560271975;4115.464989551399;4118.649914757219;4145.0880806222585;5539.362973258047;5697.344805347355;5717.685527296038;5719.056355701346;5728.169415434739;6066.998945174085;6124.960759790047;6135.845176233341;6149.323574406902;6155.938440615626;5790.218320526675;5794.739236734357;5800.0429929062075;5819.388964158787;5821.7380217890295;5080.187170052774;5185.688858668492;5303.0454518186325;5306.893862122736;5312.123499622597;6211.65835879031;6305.527085468608;6316.905246421307;6326.841828954221;6330.683465826783;6107.053406721965;6115.052166914135;6120.143179954899;6121.632702037892;6122.44897959168;5964.671943043174;6083.117947897442;6243.125962365363;6325.462335701517;6326.810195694136;5666.433356664301;5892.911606325994;5894.145348924296;5899.38056504078;5931.822045338647;6004.909926351195;6011;6015.007523758531;6015.87968240624;6019.548533860066;5753.2520772301;5850.385709500441;5855;5863.091220860773;5913.083472061838;5924.674142922181;6036.698165091816;6037.245344331958;6068.939310606837;6069.666168360783;5920.792772253123;5949.970250148895;5962.70186490665;5972.193753843176;5972.313183983723;5691.630044047124;5825.0388685866355;5992.161097446448;6048.879022419439;6063.727132279147;5771.134329850388;5824.767009319579;5828.475437210672;5832.270966129234;5869.647821130575 22:14:55 INFO - PROCESS | 13209 | |1;ext.html;585.6222736335064;644.4296797334359;656.5305806348459;660.9272979972205;662.0962386342644;34184.14539636507;45161.54838451614;45647.77176114123;45743.08513829721;45790;48980.51019489804;49435.258471122965;49469.50530494694;49641;49660.75169624156;8515.148485151467;9230.953845230764;9378.85931711025;9399.57701903416;10940.12479001678;10833.566657333697;11182.384968826711;11189.832152517718;11193.384363859985;11213.943930280378;11200.663980080612;11256.662300130969;11283;11290.435478226096;11296;11118.888811111867;11175.497102630401;11178.720531986684;11178.776424471505;11220.326780393203;10562.31344962575;10629.46852657364;10629.5216715248;10633.255672102956;10641.57433702651;45479;45512.08975820498;45913.54086459126;45997.310040349425;46034;3689.4281386385155;3824.330742120118;3844.4233364995193;3864.188520410717;3938.8424463021443;35583.82208088969;35723.82138089319;35770.10574735626;35800.10499737501;36092.09769755751;4697.412823397075;4748.395845901502;4753.643476739224;4814.542618451242;4848.10310092634;270409.64795176097;271236;271725;273198;273290;62756;62866.37133628697;63271;63491.412714682505;63579;39229.215415692095;39890.60109398869;39891.80054099711;42408.78795606064;42598.36102458404;19276.518087047934;19463;19488.317908873123;19491.512712182026;19509.414717558495;13590.116642418212;13801.930990345185;14316.785248221286;14785;14833.62915927089;20934.58130837375;21046.052927617988;21122.577548448946;21213.787862121182;21232.36302910915;7609.353204977639;7635.580043097683;7728.497647652885;7827.25641064098;7831.804204894923;1063.9148868090533;1100.2793170473353;1115.6095366621616;1153.6827372472637;1211.6789050901518;34811.65188348084;34824.30351392958;34866.35807077836;35328;35599.822000890344;1164.959226427071;1188.8276199950958;1211.8424604801319;1279.449836570265;1304.3478260869565;17593.208305626278;18761.249550017845;18961.525961850788;18964.336248231244;18976.240950362106;672655;676796;679028;679281;681209;65425;65964.34035659581;65974;66070.33929660737;66129;1286.8906142977764;1437.295725094696;1449.9420023198954;1453.0918176139912;1486.115761122109;33534.329313414084;35200.647993519735;36272.27455450929;36299;36508;52894.20658690046;53429.732851335495;53490;53549.46450535601;53557.464425356804;421754;422645;423636;425099;426557;27029.72970270272;29131.563026555043;29970.400591987604;30105.548416773327;32108.357832843678;51300.74349628228;51462.48537514577;51835.48164518306;52094.739526302124;52333;43623;44036;44151.77924110359;44711;45946;43521.34717979169;45286.320705190104;45436.31845522386;45669;46727;45791.77104114458;46459.767701161276;46737;46944.29583556318;47071.76464117794;36627.45058824067;39453.408198877616;39993.80003099966;40151.79924100361;40894;29264.41471170607;30737.846310768302;31065.8446707765;31291.687083128876;32174.356512869144 22:14:55 INFO - PROCESS | 13209 | |2;jquery.html;414.9273877071512;422.37144958830635;432.87446640474263;432.9696921215514;440.6496835016162;19952.30166944157;20387.59224815503;20804.687929681048;21205.257815976445;21344.89327553364;19593.314234001813;20323.187072517117;20865.58268834626;21042.263520776774;21055.578888422224;5882.823515294526;6148.8462788430425;6152.415520525543;6169.845753856144;6173.228346456693;5853.970730146343;6028.819135425944;6038.909416358758;6055.666938318391;6070.453659170674;5975.880482390349;5976.462118409342;5983.760649574034;5985.371535988724;5989.700514974234;5786.82639520813;6011.909821352683;6032.819015429523;6119;6122.112293717386;5906.734196961147;5946.108083787423;5954.315253745832;5957.374475680034;6043.516518678495;15228;15376.461823836224;15403.152826594533;15449.382024718998;15451.227438628024;2674.9598756018677;2701.0411303987;2760.2961244882626;2913.300807806111;3170.5719727836677;10572.259941804077;10760.892391076068;11012.229143959927;11057.11543076552;11130.220884538086;1941.2041063164036;2045.202371075282;2046.2019812273224;2049.405672355015;2050.682144267641;68034.65982670104;78566.42867142626;79044.60477697574;81547;82106;10796.622118225825;10996.340219586851;11211.66365009051;11272.66182014541;11464.993626115564;15869.603259918364;15898.205089745466;15902.363905443885;15918;15935.920320398323;8557.871631925525;8738.257248133852;8881.156290152425;8942.910570894337;9102.86345704815;8847.911520884838;8918;8959.104089590988;9144.3598948073;9148.8627670585;4736;4780.163471392562;4802.7838747255755;4876.951230487721;4917.680350777188;2086.2593779208464;2100.0969583079423;2142.2609199826034;2165.317924853666;2198.2635817001164;926.8192702423098;1002.4436437777103;1011;1039.3919557059055;1138.277193981811;20712.689309660367;20819.791802082087;20912.14010535821;20996.580068398853;21223.469413264793;1053.8577292065472;1062.3572738493256;1071.8981696738954;1072.0726571515584;1091.9454027298602;11232.157588180755;11734.354610496504;11781.941090294493;11948;12060.336681482428;171682;172525;172649;173151.26848731603;174055;75645.24354756493;81923;96327.51836240773;97387.51306243565;98247.50876245573;989.9505024748878;993.3890657245742;1008.1833714691123;1010.3786171504472;1017.577705252312;18164.182611782766;18661.533461663566;18763.15565799515;18789.90605046966;18951.43145705631;30954.226144346572;43992.12015759731;46168.30747538857;46205.537944621465;47352;58897.411025889196;64305;64413.677931610044;64432.35567644451;65939;8163.7142700005215;8294.460860044077;8807.51558664254;9192.724218273463;9350.111739384747;8454.746357609281;10288.228382871464;12955.870441295467;14152.575422737335;14301.57095287143;6321.399467050623;8211.83576328482;12379.195352302431;12727.872721272668;12849.486020559072;11486.712832179262;11560.190786645191;11578.363190024293;12267.69330766738;13007;12163.148579599343;12492.625221243377;12572.937135314265;12662.810057848956;12677.112602117762;8468.66125354979;8626.439281446686;8644;8684.652613895627;8884.73345799627;10458.424786636504;12612;12756.553520626734;12820.166689165548;12884.291363974697 22:14:55 INFO - PROCESS | 13209 | |3;mootools.html;1535.2554011304514;1553.6348957994871;1673.3139412840737;1772.0696634267017;1795.811439798821;9106.18044376006;9855.113039826414;9868.161206297464;9875.60497580096;9878.851817222747;8466.576671166433;8683.696070637528;8688.348373871953;8909.465432074065;9690.27322950778;2312.109837712476;2385.2605692235375;2386.892589833453;2397.184957114581;2412.2039726890084;2422.987885060581;2446.694163229596;2456.950860982788;2471.394508345458;2483.292261705414;2439.377958620549;2452.619843924195;2453.3498622864954;2459.3974476253343;2478.08310924958;2394.6527753475734;2395.6765836612094;2399.2682231919257;2406.6991626046743;2430.9513809723885;2390.2112302939986;2449.1060762821444;2451.1543517486616;2451.350392146083;2451.6445115458337;9628.90371096294;9671.419714817062;9673.37123086997;9684.951575242148;9685.70942871715;5429.214916389298;5433.293671822677;5586.86032849178;5613.691246981414;5614.691191984439;3319.286353434023;3404.1659793350786;3468.0982944434377;3468.6704763047724;3527.94708079379;1088.2980477591989;1116.6929094499078;1123.376526028054;1124.791913496006;1125.8874112588758;13763.931180344034;13822.93088534561;13856.584302470941;13876.167429954134;13890.305484725723;4778.163821331323;4791.401074865642;4842.176829938919;4922.360093187935;4945.752712364368;11491.827622585668;11523.711907202387;11544.249623774427;11554;11580.305181689126;5678.801241956512;5688.175214593861;5692.544596432275;5695.088785794253;5703.486686198262;4518.277075667877;4705.364775755298;4980.975095124501;5194.92207616886;5219.686818790885;4253.21315556623;4281.657467402662;4358.346248062816;4390.319500477431;4393.51671316155;1322.1141834970592;1339.055965544289;1339.9397027133803;1351.7631367298811;1353.262471952788;971.5725080964353;1066.4241309692834;1070.373831308693;1075.521392980132;1082.7996820588216;6392.680365981775;6603.636799976048;6624.900626490607;6679.2318883329035;6841.897371539431;502.18395107949584;511.07495433265166;1080.114306268852;1091.3670071358592;1119.4234968990977;4451.443569553806;4626.745528995937;4675.111728771522;4744.881377965578;4751.64362672801;11868.518647404428;12248.81626775599;12275.57035503771;12458.377081146087;12469.937650311871;12079.637610871689;12230.449629766681;12416.255024698547;12421.44103515344;12445.25328480276;8993.370464067453;9122.680706175252;9134.497602631785;9152.54237288133;9155.679551215675;6651.800445986434;6660.067590537232;6689.899651505329;6705.631190284581;6738.73045078211;10974.615888443866;10992.945035274772;11417.714557136138;11423.543058277908;11501.769964600493;11137.610183643857;11340.489677964346;11435.142364322543;11515.53937842477;11564.710882228012;7369.189389167294;8042.276195142465;8253.215944485264;8294.58527073632;8390.664373424994;9607.951960240154;9693.22454203648;9699.030096990244;9703.07820757027;9708.077732615391;8454.239118479367;8659.956700216459;8699.478031318142;8699.652013919373;8870.201681848665;8149.348052155932;8173.060098088796;8337.749867503984;8377.204165604258;8423.410361274651;7466.328030477283;7628.580428076509;7686.807829804299;7687.961560192387;7714.961425192838;5664.943350566442;5811.4479124483105;5833.0375488044;5890.204822349099;5957.374475680164;5869.148973398918;5933.495652869382;5937.584369094295;5971.522278217819;5988.221531200916 22:14:55 INFO - PROCESS | 13209 | |4;prototype.html;340.76487223815565;348.23909757180576;349.16026955172816;350.12294203021435;352.5117711968923;5521.889562208758;5576.944230557693;5580.916286255704;5581.441855814416;5588.758520058366;5462.180672899067;5479.232907392969;5480.424555421673;5485.561155107592;5488.286522752037;3909.3940439231824;4137.379393091038;4302.462192225972;4321.870343889673;4352.97823510882;4342.652587792969;4346.434963454746;4358.2808836541935;4365.934510982322;4369.868903932871;4335.956640433603;4338.327559228309;4338.8047537860875;4352.12957408518;4397.384366188736;4083.673306135502;4220.767857767822;4267.893302667427;4381.145676593065;4382.189294980439;3738.738288319819;3746.082209858575;3746.4754934309217;3751.4244017512437;3755.286495565834;5404.702741349224;5408.998670491943;5412.783488660449;5422.457754224585;5435.1847222916485;4816.301636262758;4920.286558449041;4925.236588328815;4926.187179115442;4950.851474455772;5688.630239034491;5746.626469279525;5778.6243894147165;5789.189513468118;5796.304443466769;4239.664505680732;4242.55453177415;4243.978780106079;4246.830126794925;4264.914701705949;5744.626599271034;5966.403359664042;6021.397860214031;6024.849378765566;6066.605670631395;4227.471566054243;4238.554951730086;4239.936400953989;4241.766702831374;4246.256905041605;4280.914381712349;4303.505096913832;4317.41714868495;4363.956360436355;4368.847090351823;4826.227803551415;4892.559669629679;4941.72820494869;4967.751612419364;4997.3253610762085;5798.449147330997;5812.1178210302705;5814.94185058144;5823;5848.210491583667;1483.5178566965778;1486.2123074770388;1495.1701805497835;1495.3719437836135;1498.2808252039083;895.9865602015975;941.8116376724682;946.3753922411175;958.8034452937131;958.8897276813116;5001.474255500949;5033.382641026646;5033.803534755383;5037.748112594356;5044.394672639233;5224.686518808883;5230.424653288135;5237.869053273698;5275.419703832576;5279.973600132052;5006.549410552995;5010.597032830813;5033.219850923039;5242.633015688942;5693.121269980897;3139.780215384901;3159.3997140543224;3163.288260141496;3166.920826979344;3168.746500279972;6008.87538479988;6149.600275982047;6161.7227224775;6211.9689401553605;6212.968935155386;5793.449622285876;5966.481005070293;5990.28116625999;6055.636661800306;6072.878542429126;5909.463539479681;6086.847828804315;6111.472131967008;6111.57218994675;6129.785457508877;5894.587378883437;5935.495482883831;5936.228290322403;5944.346121926498;5948.4943779779205;5661.348944871228;5896.882062358814;5901.3213480450295;5945.464908158287;5971.58198926071;6191.133241346305;6197.938020619736;6208.906866397098;6209.813705588839;6223.315435302204;4113.4858142732155;4396;4396.450443694538;4403.383526306384;4408.933865992077;5919.408059194218;5937.317208521075;5937.515621094726;5937.940620593911;5939.524838013035;5821.155932389695;5826.679532625746;5833.270841144857;5833.649981001154;5862.794802182074;5680.857978550569;5740.6268592541355;5781.103928891029;5820.70896455184;5835.241418615553;5763.809752294102;5768.452401772795;5776.797812076557;5791.507721843691;5793.275840519935;5667.744951477111;5835.54111472132;5836.445537673914;5838.6204896683275;5842.909963025814;5703.602617358773;5714.771409143587;5718.25662663851;5741.167530708108;5746.195532625352 22:14:55 INFO - PROCESS | 13209 | |5;yui.html;823.1028179284579;823.4482896459372;826.1696994520507;896.202379881905;899.1772528136755;16821.07484088376;18089.4573162805;18102.094895255235;18110.81889181108;18123.36568220111;16292.42976495823;16393.42623008195;16584.58538536537;16659.83340166598;16676.58308542287;720.0351528951218;866.800635853754;887.0995939121797;888.0497867282011;891.0376793063476;889.3863234368265;890.536920801183;891.189017993626;892.3708785306376;895.5253715530779;930.6975233049286;931.6413180925358;932.1750251027855;932.2681694869528;932.6222879733684;925.9629614815399;927.7355953553239;927.8654595083675;928.2852203803068;928.3362395886905;673.599208470959;678.253920687245;680.1158493957835;680.2755065854898;680.5916450129932;15966.441174558951;16008.439704610282;16034.438794642132;16038.278277477542;16069.598260043593;13974.091684040504;14203;14628.122312661168;14890.702185956221;15005.924970375187;13542.45830166792;13598;13610.727785444236;13638.93180534091;13906.93046534771;4552.658550608718;4580.7938642761155;4584.770761461947;4635.976820115911;4701.247800351927;68563.65718171377;70120.29879701188;70212.29787702109;77575;77991.61004195;12715.173513721578;12899.161554498927;12992.805107923388;13044.54344097971;13303.201807891557;22718.20486283005;22736.09055637756;22737.204197852996;22755.544889102126;22773.658395124086;9245.72262832116;9269.119433653792;9446.433214007182;9546.379485333431;9559;5136.666116702402;5250.264962905197;5274.4198138204765;5350.946490535122;5361.973190134102;3974.7813870237414;4138.358554424069;4142.46148000758;4160.521540022936;4162.666986641061;743.5835931878057;750.2235186581862;750.5459197185734;752.5973604121768;761.6991288441035;682.2018238660779;777.9183185765527;805.0862271322092;816.2531283875188;831.7380025292014;10382.169426445866;10872.402017889091;11084.722881927855;11337.26307789991;11668.70828229301;667.8330417395651;708.3837061756306;724.2250791652987;802.6307898366816;853.2619284319144;5090.185570308733;5401.73263122137;5464.644798088111;5491.862703432446;5511.669299842022;135572.32213839065;136429;137576.31211843877;138847;139955;102306;116055.41972290084;118626.40686796511;120741;121288;80216;102853.48573257236;102869.56175774286;104998.47500762447;105869.47065264624;10414;10475.94762026185;10493.685189444328;10557.78884422292;10695.786084278427;41498.2656651606;56703;58190;58445.415545845695;59601.70199148977;69546.30453695398;69617.30382696108;71441;80184;82328;13841.03112782136;13932.860671393562;14479.85520144785;14617.853821462075;14758.114513129247;16338.673226535166;16458.51873331406;16470.176491175633;17526.211320490354;17560.473185804447;15585.454509092127;15595.37618495293;15695.68608627844;16631.251593678557;16649.250783715004;13647.795283070564;13686.52097176594;14009.579712608638;14760.926195368955;14788.482403115839;14447.927760361132;14678.412863485768;15113.16877571744;15346.155961421782;15661.216939153224;11448.885511145116;11454.599089031844;11473.483693233991;11905.285682859056;12540.686482838002;12160.817587736014;12275.75448491043;12325.50697972071;12375.566855160027;12689.682757931125 22:14:55 INFO - PROCESS | 13209 | __end_tp_report 22:14:55 INFO - PROCESS | 13209 | __start_cc_report 22:14:55 INFO - PROCESS | 13209 | _x_x_mozilla_cycle_collect,1096 22:14:55 INFO - PROCESS | 13209 | __end_cc_report 22:14:55 INFO - PROCESS | 13209 | __startTimestamp1477545295185__endTimestamp 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | ------- Summary: start ------- 22:14:55 INFO - PROCESS | 13209 | Number of tests: 6 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#0] dojo.html Cycles:165 Average:5598.25 Median:5824.77 stddev:644.29 (11.1%) stddev-sans-first:617.22 22:14:55 INFO - PROCESS | 13209 | Values: 3159.7 3185.3 3204.9 3227.7 3255.7 5560.0 5649.5 5653.7 5665.7 5666.5 5648.4 5695.1 5699.3 5710.0 5722.1 5144.4 5148.5 5165.9 5199.7 5222.2 5011.2 5206.8 5229.6 5233.2 5262.4 4967.8 5047.6 5052.6 5055.2 5096.8 5072.1 5087.2 5121.7 5197.7 5197.9 4995.2 5093.3 5154.9 5164.6 5194.6 5436.2 5448.1 5474.0 5493.3 5592.6 5912.7 5926.9 5966.4 6019.4 6020.2 5793.4 6021.4 6055.5 6068.9 6070.8 5499.9 5515.0 5536.8 5544.7 5573.9 6100.0 6123.3 6139.4 6139.9 6146.3 5692.0 5857.5 5879.9 5882.4 5896.1 5937.1 6044.4 6052.5 6054.5 6059.8 5974.6 5990.9 6000.4 6003.3 6003.8 5649.4 5891.0 6027.8 6030.1 6040.2 4573.0 4596.7 4598.7 4623.8 4625.4 4074.7 4108.8 4115.5 4118.6 4145.1 5539.4 5697.3 5717.7 5719.1 5728.2 6067.0 6125.0 6135.8 6149.3 6155.9 5790.2 5794.7 5800.0 5819.4 5821.7 5080.2 5185.7 5303.0 5306.9 5312.1 6211.7 6305.5 6316.9 6326.8 6330.7 6107.1 6115.1 6120.1 6121.6 6122.4 5964.7 6083.1 6243.1 6325.5 6326.8 5666.4 5892.9 5894.1 5899.4 5931.8 6004.9 6011.0 6015.0 6015.9 6019.5 5753.3 5850.4 5855.0 5863.1 5913.1 5924.7 6036.7 6037.2 6068.9 6069.7 5920.8 5950.0 5962.7 5972.2 5972.3 5691.6 5825.0 5992.2 6048.9 6063.7 5771.1 5824.8 5828.5 5832.3 5869.6 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#1] ext.html Cycles:180 Average:63280.53 Median:3854.31 stddev:130144.37 (3376.6%) stddev-sans-first:130424.33 22:14:55 INFO - PROCESS | 13209 | Values: 585.6 644.4 656.5 660.9 662.1 34184.1 45161.5 45647.8 45743.1 45790.0 48980.5 49435.3 49469.5 49641.0 49660.8 8515.1 9231.0 9378.9 9399.6 10940.1 10833.6 11182.4 11189.8 11193.4 11213.9 11200.7 11256.7 11283.0 11290.4 11296.0 11118.9 11175.5 11178.7 11178.8 11220.3 10562.3 10629.5 10629.5 10633.3 10641.6 45479.0 45512.1 45913.5 45997.3 46034.0 3689.4 3824.3 3844.4 3864.2 3938.8 35583.8 35723.8 35770.1 35800.1 36092.1 4697.4 4748.4 4753.6 4814.5 4848.1 270409.6 271236.0 271725.0 273198.0 273290.0 62756.0 62866.4 63271.0 63491.4 63579.0 39229.2 39890.6 39891.8 42408.8 42598.4 19276.5 19463.0 19488.3 19491.5 19509.4 13590.1 13801.9 14316.8 14785.0 14833.6 20934.6 21046.1 21122.6 21213.8 21232.4 7609.4 7635.6 7728.5 7827.3 7831.8 1063.9 1100.3 1115.6 1153.7 1211.7 34811.7 34824.3 34866.4 35328.0 35599.8 1165.0 1188.8 1211.8 1279.4 1304.3 17593.2 18761.2 18961.5 18964.3 18976.2 672655.0 676796.0 679028.0 679281.0 681209.0 65425.0 65964.3 65974.0 66070.3 66129.0 1286.9 1437.3 1449.9 1453.1 1486.1 33534.3 35200.6 36272.3 36299.0 36508.0 52894.2 53429.7 53490.0 53549.5 53557.5 421754.0 422645.0 423636.0 425099.0 426557.0 27029.7 29131.6 29970.4 30105.5 32108.4 51300.7 51462.5 51835.5 52094.7 52333.0 43623.0 44036.0 44151.8 44711.0 45946.0 43521.3 45286.3 45436.3 45669.0 46727.0 45791.8 46459.8 46737.0 46944.3 47071.8 36627.5 39453.4 39993.8 40151.8 40894.0 29264.4 30737.8 31065.8 31291.7 32174.4 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#2] jquery.html Cycles:180 Average:20365.25 Median:11743.58 stddev:32959.66 (280.7%) stddev-sans-first:33018.08 22:14:55 INFO - PROCESS | 13209 | Values: 414.9 422.4 432.9 433.0 440.6 19952.3 20387.6 20804.7 21205.3 21344.9 19593.3 20323.2 20865.6 21042.3 21055.6 5882.8 6148.8 6152.4 6169.8 6173.2 5854.0 6028.8 6038.9 6055.7 6070.5 5975.9 5976.5 5983.8 5985.4 5989.7 5786.8 6011.9 6032.8 6119.0 6122.1 5906.7 5946.1 5954.3 5957.4 6043.5 15228.0 15376.5 15403.2 15449.4 15451.2 2675.0 2701.0 2760.3 2913.3 3170.6 10572.3 10760.9 11012.2 11057.1 11130.2 1941.2 2045.2 2046.2 2049.4 2050.7 68034.7 78566.4 79044.6 81547.0 82106.0 10796.6 10996.3 11211.7 11272.7 11465.0 15869.6 15898.2 15902.4 15918.0 15935.9 8557.9 8738.3 8881.2 8942.9 9102.9 8847.9 8918.0 8959.1 9144.4 9148.9 4736.0 4780.2 4802.8 4877.0 4917.7 2086.3 2100.1 2142.3 2165.3 2198.3 926.8 1002.4 1011.0 1039.4 1138.3 20712.7 20819.8 20912.1 20996.6 21223.5 1053.9 1062.4 1071.9 1072.1 1091.9 11232.2 11734.4 11781.9 11948.0 12060.3 171682.0 172525.0 172649.0 173151.3 174055.0 75645.2 81923.0 96327.5 97387.5 98247.5 990.0 993.4 1008.2 1010.4 1017.6 18164.2 18661.5 18763.2 18789.9 18951.4 30954.2 43992.1 46168.3 46205.5 47352.0 58897.4 64305.0 64413.7 64432.4 65939.0 8163.7 8294.5 8807.5 9192.7 9350.1 8454.7 10288.2 12955.9 14152.6 14301.6 6321.4 8211.8 12379.2 12727.9 12849.5 11486.7 11560.2 11578.4 12267.7 13007.0 12163.1 12492.6 12572.9 12662.8 12677.1 8468.7 8626.4 8644.0 8684.7 8884.7 10458.4 12612.0 12756.6 12820.2 12884.3 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#3] mootools.html Cycles:180 Average:6362.77 Median:4690.24 stddev:3723.74 (79.4%) stddev-sans-first:3716.51 22:14:55 INFO - PROCESS | 13209 | Values: 1535.3 1553.6 1673.3 1772.1 1795.8 9106.2 9855.1 9868.2 9875.6 9878.9 8466.6 8683.7 8688.3 8909.5 9690.3 2312.1 2385.3 2386.9 2397.2 2412.2 2423.0 2446.7 2457.0 2471.4 2483.3 2439.4 2452.6 2453.3 2459.4 2478.1 2394.7 2395.7 2399.3 2406.7 2431.0 2390.2 2449.1 2451.2 2451.4 2451.6 9628.9 9671.4 9673.4 9685.0 9685.7 5429.2 5433.3 5586.9 5613.7 5614.7 3319.3 3404.2 3468.1 3468.7 3527.9 1088.3 1116.7 1123.4 1124.8 1125.9 13763.9 13822.9 13856.6 13876.2 13890.3 4778.2 4791.4 4842.2 4922.4 4945.8 11491.8 11523.7 11544.2 11554.0 11580.3 5678.8 5688.2 5692.5 5695.1 5703.5 4518.3 4705.4 4981.0 5194.9 5219.7 4253.2 4281.7 4358.3 4390.3 4393.5 1322.1 1339.1 1339.9 1351.8 1353.3 971.6 1066.4 1070.4 1075.5 1082.8 6392.7 6603.6 6624.9 6679.2 6841.9 502.2 511.1 1080.1 1091.4 1119.4 4451.4 4626.7 4675.1 4744.9 4751.6 11868.5 12248.8 12275.6 12458.4 12469.9 12079.6 12230.4 12416.3 12421.4 12445.3 8993.4 9122.7 9134.5 9152.5 9155.7 6651.8 6660.1 6689.9 6705.6 6738.7 10974.6 10992.9 11417.7 11423.5 11501.8 11137.6 11340.5 11435.1 11515.5 11564.7 7369.2 8042.3 8253.2 8294.6 8390.7 9608.0 9693.2 9699.0 9703.1 9708.1 8454.2 8660.0 8699.5 8699.7 8870.2 8149.3 8173.1 8337.7 8377.2 8423.4 7466.3 7628.6 7686.8 7688.0 7715.0 5664.9 5811.4 5833.0 5890.2 5957.4 5869.1 5933.5 5937.6 5971.5 5988.2 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#4] prototype.html Cycles:180 Average:4844.52 Median:5479.83 stddev:1420.24 (25.9%) stddev-sans-first:1383.41 22:14:55 INFO - PROCESS | 13209 | Values: 340.8 348.2 349.2 350.1 352.5 5521.9 5576.9 5580.9 5581.4 5588.8 5462.2 5479.2 5480.4 5485.6 5488.3 3909.4 4137.4 4302.5 4321.9 4353.0 4342.7 4346.4 4358.3 4365.9 4369.9 4336.0 4338.3 4338.8 4352.1 4397.4 4083.7 4220.8 4267.9 4381.1 4382.2 3738.7 3746.1 3746.5 3751.4 3755.3 5404.7 5409.0 5412.8 5422.5 5435.2 4816.3 4920.3 4925.2 4926.2 4950.9 5688.6 5746.6 5778.6 5789.2 5796.3 4239.7 4242.6 4244.0 4246.8 4264.9 5744.6 5966.4 6021.4 6024.8 6066.6 4227.5 4238.6 4239.9 4241.8 4246.3 4280.9 4303.5 4317.4 4364.0 4368.8 4826.2 4892.6 4941.7 4967.8 4997.3 5798.4 5812.1 5814.9 5823.0 5848.2 1483.5 1486.2 1495.2 1495.4 1498.3 896.0 941.8 946.4 958.8 958.9 5001.5 5033.4 5033.8 5037.7 5044.4 5224.7 5230.4 5237.9 5275.4 5280.0 5006.5 5010.6 5033.2 5242.6 5693.1 3139.8 3159.4 3163.3 3166.9 3168.7 6008.9 6149.6 6161.7 6212.0 6213.0 5793.4 5966.5 5990.3 6055.6 6072.9 5909.5 6086.8 6111.5 6111.6 6129.8 5894.6 5935.5 5936.2 5944.3 5948.5 5661.3 5896.9 5901.3 5945.5 5971.6 6191.1 6197.9 6208.9 6209.8 6223.3 4113.5 4396.0 4396.5 4403.4 4408.9 5919.4 5937.3 5937.5 5937.9 5939.5 5821.2 5826.7 5833.3 5833.6 5862.8 5680.9 5740.6 5781.1 5820.7 5835.2 5763.8 5768.5 5776.8 5791.5 5793.3 5667.7 5835.5 5836.4 5838.6 5842.9 5703.6 5714.8 5718.3 5741.2 5746.2 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - PROCESS | 13209 | [#5] yui.html Cycles:180 Average:22999.52 Median:22736.65 stddev:33903.12 (149.1%) stddev-sans-first:33957.34 22:14:55 INFO - PROCESS | 13209 | Values: 823.1 823.4 826.2 896.2 899.2 16821.1 18089.5 18102.1 18110.8 18123.4 16292.4 16393.4 16584.6 16659.8 16676.6 720.0 866.8 887.1 888.0 891.0 889.4 890.5 891.2 892.4 895.5 930.7 931.6 932.2 932.3 932.6 926.0 927.7 927.9 928.3 928.3 673.6 678.3 680.1 680.3 680.6 15966.4 16008.4 16034.4 16038.3 16069.6 13974.1 14203.0 14628.1 14890.7 15005.9 13542.5 13598.0 13610.7 13638.9 13906.9 4552.7 4580.8 4584.8 4636.0 4701.2 68563.7 70120.3 70212.3 77575.0 77991.6 12715.2 12899.2 12992.8 13044.5 13303.2 22718.2 22736.1 22737.2 22755.5 22773.7 9245.7 9269.1 9446.4 9546.4 9559.0 5136.7 5250.3 5274.4 5350.9 5362.0 3974.8 4138.4 4142.5 4160.5 4162.7 743.6 750.2 750.5 752.6 761.7 682.2 777.9 805.1 816.3 831.7 10382.2 10872.4 11084.7 11337.3 11668.7 667.8 708.4 724.2 802.6 853.3 5090.2 5401.7 5464.6 5491.9 5511.7 135572.3 136429.0 137576.3 138847.0 139955.0 102306.0 116055.4 118626.4 120741.0 121288.0 80216.0 102853.5 102869.6 104998.5 105869.5 10414.0 10475.9 10493.7 10557.8 10695.8 41498.3 56703.0 58190.0 58445.4 59601.7 69546.3 69617.3 71441.0 80184.0 82328.0 13841.0 13932.9 14479.9 14617.9 14758.1 16338.7 16458.5 16470.2 17526.2 17560.5 15585.5 15595.4 15695.7 16631.3 16649.3 13647.8 13686.5 14009.6 14760.9 14788.5 14447.9 14678.4 15113.2 15346.2 15661.2 11448.9 11454.6 11473.5 11905.3 12540.7 12160.8 12275.8 12325.5 12375.6 12689.7 22:14:55 INFO - PROCESS | 13209 | -------- Summary: end -------- 22:14:55 INFO - PROCESS | 13209 | 22:14:55 INFO - TEST-INFO | 13209: exit 0 22:14:55 INFO - TEST-OK | dromaeo_css | took 1112012ms 22:14:55 INFO - TEST-START | kraken 22:14:55 INFO - operating with platform_type : linux_ 22:14:55 INFO - Initialising browser for kraken test... 22:14:55 INFO - TEST-INFO | started process 13399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFzqk59/profile http://localhost:56006/getInfo.html) 22:14:59 INFO - PROCESS | 13399 | __metrics Screen width/height:1600/1200 22:14:59 INFO - PROCESS | 13399 | colorDepth:24 22:14:59 INFO - PROCESS | 13399 | Browser inner width/height: 1024/697 22:14:59 INFO - PROCESS | 13399 | __metrics 22:15:00 INFO - PROCESS | 13399 | 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" 22:15:00 INFO - PROCESS | 13399 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:15:01 INFO - TEST-INFO | 13399: exit 0 22:15:01 INFO - Browser initialized. 22:15:01 INFO - Running cycle 1/1 for kraken test... 22:15:01 INFO - TEST-INFO | started process 13486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFzqk59/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:12 INFO - PROCESS | 13486 | RSS: Main: 144486400 22:15:12 INFO - PROCESS | 13486 | 22:15:15 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56006/tests/kraken/driver.html?testName=audio-beat-detection) 22:15:16 INFO - PROCESS | 13486 | RSS: Main: 199806976 22:15:16 INFO - PROCESS | 13486 | 22:15:18 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56006/tests/kraken/driver.html?testName=audio-dft) 22:15:19 INFO - PROCESS | 13486 | RSS: Main: 184487936 22:15:19 INFO - PROCESS | 13486 | 22:15:22 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56006/tests/kraken/driver.html?testName=audio-fft) 22:15:22 INFO - PROCESS | 13486 | RSS: Main: 275603456 22:15:22 INFO - PROCESS | 13486 | 22:15:24 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56006/tests/kraken/driver.html?testName=audio-oscillator) 22:15:25 INFO - PROCESS | 13486 | RSS: Main: 180699136 22:15:25 INFO - PROCESS | 13486 | 22:15:27 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56006/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:28 INFO - PROCESS | 13486 | RSS: Main: 180068352 22:15:28 INFO - PROCESS | 13486 | 22:15:31 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56006/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:31 INFO - PROCESS | 13486 | RSS: Main: 215724032 22:15:31 INFO - PROCESS | 13486 | 22:15:35 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56006/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:35 INFO - PROCESS | 13486 | RSS: Main: 214233088 22:15:35 INFO - PROCESS | 13486 | 22:15:38 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56006/tests/kraken/driver.html?testName=json-parse-financial) 22:15:38 INFO - PROCESS | 13486 | RSS: Main: 214859776 22:15:38 INFO - PROCESS | 13486 | 22:15:40 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56006/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:41 INFO - PROCESS | 13486 | RSS: Main: 176275456 22:15:41 INFO - PROCESS | 13486 | 22:15:42 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:43 INFO - PROCESS | 13486 | RSS: Main: 183431168 22:15:43 INFO - PROCESS | 13486 | 22:15:45 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:45 INFO - PROCESS | 13486 | RSS: Main: 189476864 22:15:45 INFO - PROCESS | 13486 | 22:15:48 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:15:49 INFO - PROCESS | 13486 | RSS: Main: 197931008 22:15:49 INFO - PROCESS | 13486 | 22:15:51 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:15:52 INFO - PROCESS | 13486 | RSS: Main: 194109440 22:15:52 INFO - PROCESS | 13486 | 22:15:53 INFO - PROCESS | 13486 | Cycle 1(1): loaded http://localhost:56006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56006/tests/kraken/driver.html?testName=ai-astar) 22:15:54 INFO - PROCESS | 13486 | RSS: Main: 244883456 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | __start_tp_report 22:15:54 INFO - PROCESS | 13486 | _x_x_mozilla_page_load 22:15:54 INFO - PROCESS | 13486 | _x_x_mozilla_page_load_details 22:15:54 INFO - PROCESS | 13486 | |i|pagename|runs| 22:15:54 INFO - PROCESS | 13486 | |0;ai-astar;110;111;120;111;109;110;109;110;109;109 22:15:54 INFO - PROCESS | 13486 | |1;audio-beat-detection;140;116;146;169;117;142;147;118;177;117 22:15:54 INFO - PROCESS | 13486 | |2;audio-dft;162;160;161;161;161;161;158;162;160;159 22:15:54 INFO - PROCESS | 13486 | |3;audio-fft;74;74;74;168;76;75;74;76;179;75 22:15:54 INFO - PROCESS | 13486 | |4;audio-oscillator;84;84;156;83;83;83;135;83;83;140 22:15:54 INFO - PROCESS | 13486 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;116;116 22:15:54 INFO - PROCESS | 13486 | |6;imaging-darkroom;146;146;145;146;146;145;145;147;145;146 22:15:54 INFO - PROCESS | 13486 | |7;imaging-desaturate;94;94;94;94;94;94;95;95;95;94 22:15:54 INFO - PROCESS | 13486 | |8;json-parse-financial;47;49;48;49;48;48;48;49;49;49 22:15:54 INFO - PROCESS | 13486 | |9;json-stringify-tinderbox;61;60;61;62;61;61;61;61;61;66 22:15:54 INFO - PROCESS | 13486 | |10;stanford-crypto-aes;73;73;72;73;76;72;73;74;76;81 22:15:54 INFO - PROCESS | 13486 | |11;stanford-crypto-ccm;122;121;134;122;118;134;116;119;157;122 22:15:54 INFO - PROCESS | 13486 | |12;stanford-crypto-pbkdf2;154;151;154;152;156;152;151;152;150;152 22:15:54 INFO - PROCESS | 13486 | |13;stanford-crypto-sha256-iterative;61;63;61;60;62;64;61;62;61;60 22:15:54 INFO - PROCESS | 13486 | __end_tp_report 22:15:54 INFO - PROCESS | 13486 | __start_cc_report 22:15:54 INFO - PROCESS | 13486 | _x_x_mozilla_cycle_collect,1736 22:15:54 INFO - PROCESS | 13486 | __end_cc_report 22:15:54 INFO - PROCESS | 13486 | __startTimestamp1477545354187__endTimestamp 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | ------- Summary: start ------- 22:15:54 INFO - PROCESS | 13486 | Number of tests: 14 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.00 stddev:3.33 (3.0%) stddev-sans-first:3.52 22:15:54 INFO - PROCESS | 13486 | Values: 110.0 111.0 120.0 111.0 109.0 110.0 109.0 110.0 109.0 109.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:144.00 stddev:22.12 (15.4%) stddev-sans-first:23.46 22:15:54 INFO - PROCESS | 13486 | Values: 140.0 116.0 146.0 169.0 117.0 142.0 147.0 118.0 177.0 117.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.27 (0.8%) stddev-sans-first:1.22 22:15:54 INFO - PROCESS | 13486 | Values: 162.0 160.0 161.0 161.0 161.0 161.0 158.0 162.0 160.0 159.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#3] audio-fft Cycles:10 Average:94.50 Median:74.50 stddev:41.72 (56.0%) stddev-sans-first:43.59 22:15:54 INFO - PROCESS | 13486 | Values: 74.0 74.0 74.0 168.0 76.0 75.0 74.0 76.0 179.0 75.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#4] audio-oscillator Cycles:10 Average:101.40 Median:83.00 stddev:29.62 (35.7%) stddev-sans-first:30.74 22:15:54 INFO - PROCESS | 13486 | Values: 84.0 84.0 156.0 83.0 83.0 83.0 135.0 83.0 83.0 140.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:15:54 INFO - PROCESS | 13486 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 116.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 22:15:54 INFO - PROCESS | 13486 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 145.0 147.0 145.0 146.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:15:54 INFO - PROCESS | 13486 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#8] json-parse-financial Cycles:10 Average:48.40 Median:49.00 stddev:0.70 (1.4%) stddev-sans-first:0.53 22:15:54 INFO - PROCESS | 13486 | Values: 47.0 49.0 48.0 49.0 48.0 48.0 48.0 49.0 49.0 49.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.00 stddev:1.65 (2.7%) stddev-sans-first:1.74 22:15:54 INFO - PROCESS | 13486 | Values: 61.0 60.0 61.0 62.0 61.0 61.0 61.0 61.0 61.0 66.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:2.75 (3.7%) stddev-sans-first:2.88 22:15:54 INFO - PROCESS | 13486 | Values: 73.0 73.0 72.0 73.0 76.0 72.0 73.0 74.0 76.0 81.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:122.00 stddev:12.35 (10.1%) stddev-sans-first:12.99 22:15:54 INFO - PROCESS | 13486 | Values: 122.0 121.0 134.0 122.0 118.0 134.0 116.0 119.0 157.0 122.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:152.00 stddev:1.78 (1.2%) stddev-sans-first:1.79 22:15:54 INFO - PROCESS | 13486 | Values: 154.0 151.0 154.0 152.0 156.0 152.0 151.0 152.0 150.0 152.0 22:15:54 INFO - PROCESS | 13486 | 22:15:54 INFO - PROCESS | 13486 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:1.27 (2.1%) stddev-sans-first:1.33 22:15:54 INFO - PROCESS | 13486 | Values: 61.0 63.0 61.0 60.0 62.0 64.0 61.0 62.0 61.0 60.0 22:15:54 INFO - PROCESS | 13486 | -------- Summary: end -------- 22:15:54 INFO - PROCESS | 13486 | 22:15:55 INFO - TEST-INFO | 13486: exit 0 22:15:55 INFO - TEST-OK | kraken | took 59229ms 22:15:55 INFO - SUITE-END | took 1171s 22:15:55 INFO - Completed test suite (00:19:31) 22:15:55 INFO - javascript benchmark 22:15:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.6840315968398, 3185.251465905512, 3204.8718051277933, 3227.661095584965, 3255.690709382608, 5559.972200138999, 5649.463300986405, 5653.717314134292, 5665.688387138705, 5666.461686139821, 5648.350439699436, 5695.0887857942735, 5699.344575373833, 5710.029295019846, 5722.078982210583, 5144.408393034792, 5148.459411761758, 5165.870853228681, 5199.714015729133, 5222.216667499867, 5011.223260394627, 5206.817761378353, 5229.581633469333, 5233.188855727369, 5262.421133675292, 4967.776450059738, 5047.621428392867, 5052.570531504826, 5055.165897626887, 5096.79612815487, 5072.137736584789, 5087.236914462842, 5121.718305493197, 5197.7401129943355, 5197.870053248661, 4995.175795993657, 5093.286939828427, 5154.948450515484, 5164.586833053347, 5194.558462530689, 5436.184572314145, 5448.128299472065, 5473.972630136864, 5493.258410114623, 5592.5805564582415, 5912.733926973297, 5926.940730592682, 5966.403359664042, 6019.428154325332, 6020.157177995085, 5793.420657934215, 6021.36775638556, 6055.545834062463, 6068.878622427571, 6070.787522436738, 5499.945000549983, 5515.0, 5536.806211782568, 5544.69504177266, 5573.8606534836545, 6100.023996160593, 6123.2652081750475, 6139.416755408229, 6139.877202455926, 6146.262448506119, 5692.0, 5857.531397488191, 5879.941200588025, 5882.44116808911, 5896.056630939029, 5937.050071988548, 6044.395560443921, 6052.485538729169, 6054.485368743618, 6059.8485037873525, 5974.641521508636, 5990.850228744316, 6000.429959153961, 6003.279606447254, 6003.7898673546215, 5649.378568357477, 5890.970545147246, 6027.819165425044, 6030.125631783367, 6040.244969378828, 4572.977135114304, 4596.724196548218, 4598.701084429501, 4623.768811559408, 4625.375574297427, 4074.6740260779065, 4108.794560271975, 4115.464989551399, 4118.649914757219, 4145.0880806222585, 5539.362973258047, 5697.344805347355, 5717.685527296038, 5719.056355701346, 5728.169415434739, 6066.998945174085, 6124.960759790047, 6135.845176233341, 6149.323574406902, 6155.938440615626, 5790.218320526675, 5794.739236734357, 5800.0429929062075, 5819.388964158787, 5821.7380217890295, 5080.187170052774, 5185.688858668492, 5303.0454518186325, 5306.893862122736, 5312.123499622597, 6211.65835879031, 6305.527085468608, 6316.905246421307, 6326.841828954221, 6330.683465826783, 6107.053406721965, 6115.052166914135, 6120.143179954899, 6121.632702037892, 6122.44897959168, 5964.671943043174, 6083.117947897442, 6243.125962365363, 6325.462335701517, 6326.810195694136, 5666.433356664301, 5892.911606325994, 5894.145348924296, 5899.38056504078, 5931.822045338647, 6004.909926351195, 6011.0, 6015.007523758531, 6015.87968240624, 6019.548533860066, 5753.2520772301, 5850.385709500441, 5855.0, 5863.091220860773, 5913.083472061838, 5924.674142922181, 6036.698165091816, 6037.245344331958, 6068.939310606837, 6069.666168360783, 5920.792772253123, 5949.970250148895, 5962.70186490665, 5972.193753843176, 5972.313183983723, 5691.630044047124, 5825.0388685866355, 5992.161097446448, 6048.879022419439, 6063.727132279147, 5771.134329850388, 5824.767009319579, 5828.475437210672, 5832.270966129234, 5869.647821130575], "value": 5553.9931084847585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.6222736335064, 644.4296797334359, 656.5305806348459, 660.9272979972205, 662.0962386342644, 34184.14539636507, 45161.54838451614, 45647.77176114123, 45743.08513829721, 45790.0, 48980.51019489804, 49435.258471122965, 49469.50530494694, 49641.0, 49660.75169624156, 8515.148485151467, 9230.953845230764, 9378.85931711025, 9399.57701903416, 10940.12479001678, 10833.566657333697, 11182.384968826711, 11189.832152517718, 11193.384363859985, 11213.943930280378, 11200.663980080612, 11256.662300130969, 11283.0, 11290.435478226096, 11296.0, 11118.888811111867, 11175.497102630401, 11178.720531986684, 11178.776424471505, 11220.326780393203, 10562.31344962575, 10629.46852657364, 10629.5216715248, 10633.255672102956, 10641.57433702651, 45479.0, 45512.08975820498, 45913.54086459126, 45997.310040349425, 46034.0, 3689.4281386385155, 3824.330742120118, 3844.4233364995193, 3864.188520410717, 3938.8424463021443, 35583.82208088969, 35723.82138089319, 35770.10574735626, 35800.10499737501, 36092.09769755751, 4697.412823397075, 4748.395845901502, 4753.643476739224, 4814.542618451242, 4848.10310092634, 270409.64795176097, 271236.0, 271725.0, 273198.0, 273290.0, 62756.0, 62866.37133628697, 63271.0, 63491.412714682505, 63579.0, 39229.215415692095, 39890.60109398869, 39891.80054099711, 42408.78795606064, 42598.36102458404, 19276.518087047934, 19463.0, 19488.317908873123, 19491.512712182026, 19509.414717558495, 13590.116642418212, 13801.930990345185, 14316.785248221286, 14785.0, 14833.62915927089, 20934.58130837375, 21046.052927617988, 21122.577548448946, 21213.787862121182, 21232.36302910915, 7609.353204977639, 7635.580043097683, 7728.497647652885, 7827.25641064098, 7831.804204894923, 1063.9148868090533, 1100.2793170473353, 1115.6095366621616, 1153.6827372472637, 1211.6789050901518, 34811.65188348084, 34824.30351392958, 34866.35807077836, 35328.0, 35599.822000890344, 1164.959226427071, 1188.8276199950958, 1211.8424604801319, 1279.449836570265, 1304.3478260869565, 17593.208305626278, 18761.249550017845, 18961.525961850788, 18964.336248231244, 18976.240950362106, 672655.0, 676796.0, 679028.0, 679281.0, 681209.0, 65425.0, 65964.34035659581, 65974.0, 66070.33929660737, 66129.0, 1286.8906142977764, 1437.295725094696, 1449.9420023198954, 1453.0918176139912, 1486.115761122109, 33534.329313414084, 35200.647993519735, 36272.27455450929, 36299.0, 36508.0, 52894.20658690046, 53429.732851335495, 53490.0, 53549.46450535601, 53557.464425356804, 421754.0, 422645.0, 423636.0, 425099.0, 426557.0, 27029.72970270272, 29131.563026555043, 29970.400591987604, 30105.548416773327, 32108.357832843678, 51300.74349628228, 51462.48537514577, 51835.48164518306, 52094.739526302124, 52333.0, 43623.0, 44036.0, 44151.77924110359, 44711.0, 45946.0, 43521.34717979169, 45286.320705190104, 45436.31845522386, 45669.0, 46727.0, 45791.77104114458, 46459.767701161276, 46737.0, 46944.29583556318, 47071.76464117794, 36627.45058824067, 39453.408198877616, 39993.80003099966, 40151.79924100361, 40894.0, 29264.41471170607, 30737.846310768302, 31065.8446707765, 31291.687083128876, 32174.356512869144], "value": 21794.590579849853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.9273877071512, 422.37144958830635, 432.87446640474263, 432.9696921215514, 440.6496835016162, 19952.30166944157, 20387.59224815503, 20804.687929681048, 21205.257815976445, 21344.89327553364, 19593.314234001813, 20323.187072517117, 20865.58268834626, 21042.263520776774, 21055.578888422224, 5882.823515294526, 6148.8462788430425, 6152.415520525543, 6169.845753856144, 6173.228346456693, 5853.970730146343, 6028.819135425944, 6038.909416358758, 6055.666938318391, 6070.453659170674, 5975.880482390349, 5976.462118409342, 5983.760649574034, 5985.371535988724, 5989.700514974234, 5786.82639520813, 6011.909821352683, 6032.819015429523, 6119.0, 6122.112293717386, 5906.734196961147, 5946.108083787423, 5954.315253745832, 5957.374475680034, 6043.516518678495, 15228.0, 15376.461823836224, 15403.152826594533, 15449.382024718998, 15451.227438628024, 2674.9598756018677, 2701.0411303987, 2760.2961244882626, 2913.300807806111, 3170.5719727836677, 10572.259941804077, 10760.892391076068, 11012.229143959927, 11057.11543076552, 11130.220884538086, 1941.2041063164036, 2045.202371075282, 2046.2019812273224, 2049.405672355015, 2050.682144267641, 68034.65982670104, 78566.42867142626, 79044.60477697574, 81547.0, 82106.0, 10796.622118225825, 10996.340219586851, 11211.66365009051, 11272.66182014541, 11464.993626115564, 15869.603259918364, 15898.205089745466, 15902.363905443885, 15918.0, 15935.920320398323, 8557.871631925525, 8738.257248133852, 8881.156290152425, 8942.910570894337, 9102.86345704815, 8847.911520884838, 8918.0, 8959.104089590988, 9144.3598948073, 9148.8627670585, 4736.0, 4780.163471392562, 4802.7838747255755, 4876.951230487721, 4917.680350777188, 2086.2593779208464, 2100.0969583079423, 2142.2609199826034, 2165.317924853666, 2198.2635817001164, 926.8192702423098, 1002.4436437777103, 1011.0, 1039.3919557059055, 1138.277193981811, 20712.689309660367, 20819.791802082087, 20912.14010535821, 20996.580068398853, 21223.469413264793, 1053.8577292065472, 1062.3572738493256, 1071.8981696738954, 1072.0726571515584, 1091.9454027298602, 11232.157588180755, 11734.354610496504, 11781.941090294493, 11948.0, 12060.336681482428, 171682.0, 172525.0, 172649.0, 173151.26848731603, 174055.0, 75645.24354756493, 81923.0, 96327.51836240773, 97387.51306243565, 98247.50876245573, 989.9505024748878, 993.3890657245742, 1008.1833714691123, 1010.3786171504472, 1017.577705252312, 18164.182611782766, 18661.533461663566, 18763.15565799515, 18789.90605046966, 18951.43145705631, 30954.226144346572, 43992.12015759731, 46168.30747538857, 46205.537944621465, 47352.0, 58897.411025889196, 64305.0, 64413.677931610044, 64432.35567644451, 65939.0, 8163.7142700005215, 8294.460860044077, 8807.51558664254, 9192.724218273463, 9350.111739384747, 8454.746357609281, 10288.228382871464, 12955.870441295467, 14152.575422737335, 14301.57095287143, 6321.399467050623, 8211.83576328482, 12379.195352302431, 12727.872721272668, 12849.486020559072, 11486.712832179262, 11560.190786645191, 11578.363190024293, 12267.69330766738, 13007.0, 12163.148579599343, 12492.625221243377, 12572.937135314265, 12662.810057848956, 12677.112602117762, 8468.66125354979, 8626.439281446686, 8644.0, 8684.652613895627, 8884.73345799627, 10458.424786636504, 12612.0, 12756.553520626734, 12820.166689165548, 12884.291363974697], "value": 9248.784240567553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.2554011304514, 1553.6348957994871, 1673.3139412840737, 1772.0696634267017, 1795.811439798821, 9106.18044376006, 9855.113039826414, 9868.161206297464, 9875.60497580096, 9878.851817222747, 8466.576671166433, 8683.696070637528, 8688.348373871953, 8909.465432074065, 9690.27322950778, 2312.109837712476, 2385.2605692235375, 2386.892589833453, 2397.184957114581, 2412.2039726890084, 2422.987885060581, 2446.694163229596, 2456.950860982788, 2471.394508345458, 2483.292261705414, 2439.377958620549, 2452.619843924195, 2453.3498622864954, 2459.3974476253343, 2478.08310924958, 2394.6527753475734, 2395.6765836612094, 2399.2682231919257, 2406.6991626046743, 2430.9513809723885, 2390.2112302939986, 2449.1060762821444, 2451.1543517486616, 2451.350392146083, 2451.6445115458337, 9628.90371096294, 9671.419714817062, 9673.37123086997, 9684.951575242148, 9685.70942871715, 5429.214916389298, 5433.293671822677, 5586.86032849178, 5613.691246981414, 5614.691191984439, 3319.286353434023, 3404.1659793350786, 3468.0982944434377, 3468.6704763047724, 3527.94708079379, 1088.2980477591989, 1116.6929094499078, 1123.376526028054, 1124.791913496006, 1125.8874112588758, 13763.931180344034, 13822.93088534561, 13856.584302470941, 13876.167429954134, 13890.305484725723, 4778.163821331323, 4791.401074865642, 4842.176829938919, 4922.360093187935, 4945.752712364368, 11491.827622585668, 11523.711907202387, 11544.249623774427, 11554.0, 11580.305181689126, 5678.801241956512, 5688.175214593861, 5692.544596432275, 5695.088785794253, 5703.486686198262, 4518.277075667877, 4705.364775755298, 4980.975095124501, 5194.92207616886, 5219.686818790885, 4253.21315556623, 4281.657467402662, 4358.346248062816, 4390.319500477431, 4393.51671316155, 1322.1141834970592, 1339.055965544289, 1339.9397027133803, 1351.7631367298811, 1353.262471952788, 971.5725080964353, 1066.4241309692834, 1070.373831308693, 1075.521392980132, 1082.7996820588216, 6392.680365981775, 6603.636799976048, 6624.900626490607, 6679.2318883329035, 6841.897371539431, 502.18395107949584, 511.07495433265166, 1080.114306268852, 1091.3670071358592, 1119.4234968990977, 4451.443569553806, 4626.745528995937, 4675.111728771522, 4744.881377965578, 4751.64362672801, 11868.518647404428, 12248.81626775599, 12275.57035503771, 12458.377081146087, 12469.937650311871, 12079.637610871689, 12230.449629766681, 12416.255024698547, 12421.44103515344, 12445.25328480276, 8993.370464067453, 9122.680706175252, 9134.497602631785, 9152.54237288133, 9155.679551215675, 6651.800445986434, 6660.067590537232, 6689.899651505329, 6705.631190284581, 6738.73045078211, 10974.615888443866, 10992.945035274772, 11417.714557136138, 11423.543058277908, 11501.769964600493, 11137.610183643857, 11340.489677964346, 11435.142364322543, 11515.53937842477, 11564.710882228012, 7369.189389167294, 8042.276195142465, 8253.215944485264, 8294.58527073632, 8390.664373424994, 9607.951960240154, 9693.22454203648, 9699.030096990244, 9703.07820757027, 9708.077732615391, 8454.239118479367, 8659.956700216459, 8699.478031318142, 8699.652013919373, 8870.201681848665, 8149.348052155932, 8173.060098088796, 8337.749867503984, 8377.204165604258, 8423.410361274651, 7466.328030477283, 7628.580428076509, 7686.807829804299, 7687.961560192387, 7714.961425192838, 5664.943350566442, 5811.4479124483105, 5833.0375488044, 5890.204822349099, 5957.374475680164, 5869.148973398918, 5933.495652869382, 5937.584369094295, 5971.522278217819, 5988.221531200916], "value": 5008.666347154573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.76487223815565, 348.23909757180576, 349.16026955172816, 350.12294203021435, 352.5117711968923, 5521.889562208758, 5576.944230557693, 5580.916286255704, 5581.441855814416, 5588.758520058366, 5462.180672899067, 5479.232907392969, 5480.424555421673, 5485.561155107592, 5488.286522752037, 3909.3940439231824, 4137.379393091038, 4302.462192225972, 4321.870343889673, 4352.97823510882, 4342.652587792969, 4346.434963454746, 4358.2808836541935, 4365.934510982322, 4369.868903932871, 4335.956640433603, 4338.327559228309, 4338.8047537860875, 4352.12957408518, 4397.384366188736, 4083.673306135502, 4220.767857767822, 4267.893302667427, 4381.145676593065, 4382.189294980439, 3738.738288319819, 3746.082209858575, 3746.4754934309217, 3751.4244017512437, 3755.286495565834, 5404.702741349224, 5408.998670491943, 5412.783488660449, 5422.457754224585, 5435.1847222916485, 4816.301636262758, 4920.286558449041, 4925.236588328815, 4926.187179115442, 4950.851474455772, 5688.630239034491, 5746.626469279525, 5778.6243894147165, 5789.189513468118, 5796.304443466769, 4239.664505680732, 4242.55453177415, 4243.978780106079, 4246.830126794925, 4264.914701705949, 5744.626599271034, 5966.403359664042, 6021.397860214031, 6024.849378765566, 6066.605670631395, 4227.471566054243, 4238.554951730086, 4239.936400953989, 4241.766702831374, 4246.256905041605, 4280.914381712349, 4303.505096913832, 4317.41714868495, 4363.956360436355, 4368.847090351823, 4826.227803551415, 4892.559669629679, 4941.72820494869, 4967.751612419364, 4997.3253610762085, 5798.449147330997, 5812.1178210302705, 5814.94185058144, 5823.0, 5848.210491583667, 1483.5178566965778, 1486.2123074770388, 1495.1701805497835, 1495.3719437836135, 1498.2808252039083, 895.9865602015975, 941.8116376724682, 946.3753922411175, 958.8034452937131, 958.8897276813116, 5001.474255500949, 5033.382641026646, 5033.803534755383, 5037.748112594356, 5044.394672639233, 5224.686518808883, 5230.424653288135, 5237.869053273698, 5275.419703832576, 5279.973600132052, 5006.549410552995, 5010.597032830813, 5033.219850923039, 5242.633015688942, 5693.121269980897, 3139.780215384901, 3159.3997140543224, 3163.288260141496, 3166.920826979344, 3168.746500279972, 6008.87538479988, 6149.600275982047, 6161.7227224775, 6211.9689401553605, 6212.968935155386, 5793.449622285876, 5966.481005070293, 5990.28116625999, 6055.636661800306, 6072.878542429126, 5909.463539479681, 6086.847828804315, 6111.472131967008, 6111.57218994675, 6129.785457508877, 5894.587378883437, 5935.495482883831, 5936.228290322403, 5944.346121926498, 5948.4943779779205, 5661.348944871228, 5896.882062358814, 5901.3213480450295, 5945.464908158287, 5971.58198926071, 6191.133241346305, 6197.938020619736, 6208.906866397098, 6209.813705588839, 6223.315435302204, 4113.4858142732155, 4396.0, 4396.450443694538, 4403.383526306384, 4408.933865992077, 5919.408059194218, 5937.317208521075, 5937.515621094726, 5937.940620593911, 5939.524838013035, 5821.155932389695, 5826.679532625746, 5833.270841144857, 5833.649981001154, 5862.794802182074, 5680.857978550569, 5740.6268592541355, 5781.103928891029, 5820.70896455184, 5835.241418615553, 5763.809752294102, 5768.452401772795, 5776.797812076557, 5791.507721843691, 5793.275840519935, 5667.744951477111, 5835.54111472132, 5836.445537673914, 5838.6204896683275, 5842.909963025814, 5703.602617358773, 5714.771409143587, 5718.25662663851, 5741.167530708108, 5746.195532625352], "value": 4390.953443413822, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.1028179284579, 823.4482896459372, 826.1696994520507, 896.202379881905, 899.1772528136755, 16821.07484088376, 18089.4573162805, 18102.094895255235, 18110.81889181108, 18123.36568220111, 16292.42976495823, 16393.42623008195, 16584.58538536537, 16659.83340166598, 16676.58308542287, 720.0351528951218, 866.800635853754, 887.0995939121797, 888.0497867282011, 891.0376793063476, 889.3863234368265, 890.536920801183, 891.189017993626, 892.3708785306376, 895.5253715530779, 930.6975233049286, 931.6413180925358, 932.1750251027855, 932.2681694869528, 932.6222879733684, 925.9629614815399, 927.7355953553239, 927.8654595083675, 928.2852203803068, 928.3362395886905, 673.599208470959, 678.253920687245, 680.1158493957835, 680.2755065854898, 680.5916450129932, 15966.441174558951, 16008.439704610282, 16034.438794642132, 16038.278277477542, 16069.598260043593, 13974.091684040504, 14203.0, 14628.122312661168, 14890.702185956221, 15005.924970375187, 13542.45830166792, 13598.0, 13610.727785444236, 13638.93180534091, 13906.93046534771, 4552.658550608718, 4580.7938642761155, 4584.770761461947, 4635.976820115911, 4701.247800351927, 68563.65718171377, 70120.29879701188, 70212.29787702109, 77575.0, 77991.61004195, 12715.173513721578, 12899.161554498927, 12992.805107923388, 13044.54344097971, 13303.201807891557, 22718.20486283005, 22736.09055637756, 22737.204197852996, 22755.544889102126, 22773.658395124086, 9245.72262832116, 9269.119433653792, 9446.433214007182, 9546.379485333431, 9559.0, 5136.666116702402, 5250.264962905197, 5274.4198138204765, 5350.946490535122, 5361.973190134102, 3974.7813870237414, 4138.358554424069, 4142.46148000758, 4160.521540022936, 4162.666986641061, 743.5835931878057, 750.2235186581862, 750.5459197185734, 752.5973604121768, 761.6991288441035, 682.2018238660779, 777.9183185765527, 805.0862271322092, 816.2531283875188, 831.7380025292014, 10382.169426445866, 10872.402017889091, 11084.722881927855, 11337.26307789991, 11668.70828229301, 667.8330417395651, 708.3837061756306, 724.2250791652987, 802.6307898366816, 853.2619284319144, 5090.185570308733, 5401.73263122137, 5464.644798088111, 5491.862703432446, 5511.669299842022, 135572.32213839065, 136429.0, 137576.31211843877, 138847.0, 139955.0, 102306.0, 116055.41972290084, 118626.40686796511, 120741.0, 121288.0, 80216.0, 102853.48573257236, 102869.56175774286, 104998.47500762447, 105869.47065264624, 10414.0, 10475.94762026185, 10493.685189444328, 10557.78884422292, 10695.786084278427, 41498.2656651606, 56703.0, 58190.0, 58445.415545845695, 59601.70199148977, 69546.30453695398, 69617.30382696108, 71441.0, 80184.0, 82328.0, 13841.03112782136, 13932.860671393562, 14479.85520144785, 14617.853821462075, 14758.114513129247, 16338.673226535166, 16458.51873331406, 16470.176491175633, 17526.211320490354, 17560.473185804447, 15585.454509092127, 15595.37618495293, 15695.68608627844, 16631.251593678557, 16649.250783715004, 13647.795283070564, 13686.52097176594, 14009.579712608638, 14760.926195368955, 14788.482403115839, 14447.927760361132, 14678.412863485768, 15113.16877571744, 15346.155961421782, 15661.216939153224, 11448.885511145116, 11454.599089031844, 11473.483693233991, 11905.285682859056, 12540.686482838002, 12160.817587736014, 12275.75448491043, 12325.50697972071, 12375.566855160027, 12689.682757931125], "value": 8359.874428740144, "unit": "score"}], "name": "dromaeo_css", "value": 7684.114336387509, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 111.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 146.0, 169.0, 117.0, 142.0, 147.0, 118.0, 177.0, 117.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 161.0, 161.0, 158.0, 162.0, 160.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 168.0, 76.0, 75.0, 74.0, 76.0, 179.0, 75.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 156.0, 83.0, 83.0, 83.0, 135.0, 83.0, 83.0, 140.0], "value": 101.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 49.0, 48.0, 49.0, 48.0, 48.0, 48.0, 49.0, 49.0, 49.0], "value": 48.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 66.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 73.0, 76.0, 72.0, 73.0, 74.0, 76.0, 81.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 134.0, 122.0, 118.0, 134.0, 116.0, 119.0, 157.0, 122.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 154.0, 152.0, 156.0, 152.0, 151.0, 152.0, 150.0, 152.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 60.0, 62.0, 64.0, 61.0, 62.0, 61.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1487.2, "alertThreshold": 2.0}]} 22:15:55 INFO - Return code: 0 22:15:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:15:55 INFO - # TBPL SUCCESS # 22:15:55 INFO - Running post-action listener: _resource_record_post_action 22:15:55 INFO - [mozharness: 2016-10-27 05:15:55.506784Z] Finished run-tests step (success) 22:15:55 INFO - Running post-run listener: _resource_record_post_run 22:15:55 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 292306944; Read time: 56; Write time: 181316 22:15:55 INFO - TinderboxPrint: CPU usage
12.9% 22:15:55 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 56 22:15:55 INFO - TinderboxPrint: I/O write bytes / time
292,306,944 / 181,316 22:15:55 INFO - TinderboxPrint: CPU idle
8,245.4 (87.1%) 22:15:55 INFO - TinderboxPrint: CPU user
1,179.1 (12.5%) 22:15:55 INFO - TinderboxPrint: Swap in / out
0 / 0 22:15:55 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71340032; Read time: 0; Write time: 95416 22:15:55 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 220418048; Read time: 0; Write time: 85856 22:15:56 INFO - Running post-run listener: _upload_blobber_files 22:15:56 INFO - Blob upload gear active. 22:15:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:15:56 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 22:15:56 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'] 22:15:56 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 22:15:56 INFO - (blobuploader) - INFO - Open directory for files ... 22:15:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:15:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:15:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:59 INFO - (blobuploader) - INFO - Done attempting. 22:15:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:15:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:16:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:00 INFO - (blobuploader) - INFO - Done attempting. 22:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:16:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:01 INFO - (blobuploader) - INFO - Done attempting. 22:16:01 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:01 INFO - Return code: 0 22:16:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:16:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f3a37569a1cbc51a854fc5b94b17edc000aec00a23e1e240b907a7956824f37a8c971a4a7b741a1c86fa7d866779333ecfac2b844fbacf64b2fe338e950a453", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9b057d1822c49b3f85e27d6e9b7fd5c5232965ada02c445599c4800b2eff3c250c9539366a36a7ae9b70bb5e934412e8ebd9264c2fb7bd0ffc5860be5073984a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/75b0180d7e6b2dff91664f57a15bac5182bead0a164bf57c31dfdb50cee420f09e9e65ddb74f03f0054646d49a010edb119f3de5a93f586f08e7a0d021714372"} 22:16:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:16:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:16:01 INFO - Contents: 22:16:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f3a37569a1cbc51a854fc5b94b17edc000aec00a23e1e240b907a7956824f37a8c971a4a7b741a1c86fa7d866779333ecfac2b844fbacf64b2fe338e950a453", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9b057d1822c49b3f85e27d6e9b7fd5c5232965ada02c445599c4800b2eff3c250c9539366a36a7ae9b70bb5e934412e8ebd9264c2fb7bd0ffc5860be5073984a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/75b0180d7e6b2dff91664f57a15bac5182bead0a164bf57c31dfdb50cee420f09e9e65ddb74f03f0054646d49a010edb119f3de5a93f586f08e7a0d021714372"} 22:16:01 INFO - Running post-run listener: copy_logs_to_upload_dir 22:16:01 INFO - Copying logs to upload dir... 22:16:01 INFO - mkdir: /builds/slave/test/build/upload/logs 22:16:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.212740 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-26 22:16:01.328173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:16:01.331858) ========= 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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 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/4f3a37569a1cbc51a854fc5b94b17edc000aec00a23e1e240b907a7956824f37a8c971a4a7b741a1c86fa7d866779333ecfac2b844fbacf64b2fe338e950a453", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9b057d1822c49b3f85e27d6e9b7fd5c5232965ada02c445599c4800b2eff3c250c9539366a36a7ae9b70bb5e934412e8ebd9264c2fb7bd0ffc5860be5073984a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/75b0180d7e6b2dff91664f57a15bac5182bead0a164bf57c31dfdb50cee420f09e9e65ddb74f03f0054646d49a010edb119f3de5a93f586f08e7a0d021714372"} build_url:https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011510 build_url: 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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/4f3a37569a1cbc51a854fc5b94b17edc000aec00a23e1e240b907a7956824f37a8c971a4a7b741a1c86fa7d866779333ecfac2b844fbacf64b2fe338e950a453", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9b057d1822c49b3f85e27d6e9b7fd5c5232965ada02c445599c4800b2eff3c250c9539366a36a7ae9b70bb5e934412e8ebd9264c2fb7bd0ffc5860be5073984a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/75b0180d7e6b2dff91664f57a15bac5182bead0a164bf57c31dfdb50cee420f09e9e65ddb74f03f0054646d49a010edb119f3de5a93f586f08e7a0d021714372"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:16:01.357389) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:16:01.357758) ========= 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=db0483c52d987ecb441a263b000001ad-1477543684.642792-1042846003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:16:01.441747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:16:01.442091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:16:01.442517) ========= ========= Total master_lag: 0.17 =========