builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1477351011.89 results: success (0) buildid: 20161024132223 builduid: 1aec72f8d1a6414e88763a4bedd78812 revision: 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:51.895292) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:51.895799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:51.896092) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:51.911663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:51.912067) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 16:16:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.6M=0s 2016-10-24 16:16:52 (35.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092194 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.018432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.018832) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016531 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.068470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.068853) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 --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 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 --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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 16:16:52,337 truncating revision to first 12 chars 2016-10-24 16:16:52,337 Setting DEBUG logging. 2016-10-24 16:16:52,337 attempt 1/10 2016-10-24 16:16:52,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0b0bf76318dc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 16:16:52,649 unpacking tar archive at: mozilla-beta-0b0bf76318dc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651175 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.733664) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.734013) ========= script_repo_revision: 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.734459) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.734744) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 16:16:52.744512) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-24 16:16:52.744930) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:16:53 INFO - MultiFileLogger online at 20161024 16:16:53 in /builds/slave/test 16:16:53 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 16:16:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:53 INFO - {'append_to_log': False, 16:16:53 INFO - 'base_work_dir': '/builds/slave/test', 16:16:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:16:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:53 INFO - 'branch': 'Mozilla-Beta', 16:16:53 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:53 INFO - 'config_files': ('talos/linux_config.py',), 16:16:53 INFO - 'default_actions': ('clobber', 16:16:53 INFO - 'read-buildbot-config', 16:16:53 INFO - 'download-and-extract', 16:16:53 INFO - 'populate-webroot', 16:16:53 INFO - 'create-virtualenv', 16:16:53 INFO - 'install', 16:16:53 INFO - 'run-tests'), 16:16:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:53 INFO - 'download_minidump_stackwalk': True, 16:16:53 INFO - 'download_symbols': 'ondemand', 16:16:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:53 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:53 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:53 INFO - 'installer_path': 'installer.exe', 16:16:53 INFO - 'log_level': 'info', 16:16:53 INFO - 'log_name': 'talos', 16:16:53 INFO - 'log_to_console': True, 16:16:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:16:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:16:53 INFO - 'opt_config_files': (), 16:16:53 INFO - 'pip_index': False, 16:16:53 INFO - 'sps_profile': False, 16:16:53 INFO - 'sps_profile_interval': 0, 16:16:53 INFO - 'suite': 'dromaeojs', 16:16:53 INFO - 'system_bits': '32', 16:16:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:53 INFO - 'title': 'talos-linux64-ix-051', 16:16:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:53 INFO - 'use_talos_json': True, 16:16:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:53 INFO - 'work_dir': 'build'} 16:16:53 INFO - [mozharness: 2016-10-24 23:16:53.109835Z] Running clobber step. 16:16:53 INFO - Running pre-action listener: _resource_record_pre_action 16:16:53 INFO - Running main action method: clobber 16:16:53 INFO - rmtree: /builds/slave/test/build 16:16:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:53 INFO - Running post-action listener: _resource_record_post_action 16:16:53 INFO - [mozharness: 2016-10-24 23:16:53.600022Z] Finished clobber step (success) 16:16:53 INFO - [mozharness: 2016-10-24 23:16:53.600124Z] Running read-buildbot-config step. 16:16:53 INFO - Running pre-action listener: _resource_record_pre_action 16:16:53 INFO - Running main action method: read_buildbot_config 16:16:53 INFO - Using buildbot properties: 16:16:53 INFO - { 16:16:53 INFO - "project": "", 16:16:53 INFO - "product": "firefox", 16:16:53 INFO - "script_repo_revision": "production", 16:16:53 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:16:53 INFO - "repository": "", 16:16:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:16:53 INFO - "buildid": "20161024132223", 16:16:53 INFO - "pgo_build": "True", 16:16:53 INFO - "basedir": "/builds/slave/test", 16:16:53 INFO - "buildnumber": 201, 16:16:53 INFO - "slavename": "talos-linux64-ix-051", 16:16:53 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:16:53 INFO - "platform": "ubuntu64_hw", 16:16:53 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:16:53 INFO - "branch": "mozilla-beta", 16:16:53 INFO - "repo_path": "releases/mozilla-beta", 16:16:53 INFO - "revision": "0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93", 16:16:53 INFO - "stage_platform": "linux64-pgo", 16:16:53 INFO - "builduid": "1aec72f8d1a6414e88763a4bedd78812", 16:16:53 INFO - "slavebuilddir": "test" 16:16:53 INFO - } 16:16:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:16:53 INFO - Running post-action listener: _resource_record_post_action 16:16:53 INFO - [mozharness: 2016-10-24 23:16:53.602593Z] Finished read-buildbot-config step (success) 16:16:53 INFO - [mozharness: 2016-10-24 23:16:53.603229Z] Running download-and-extract step. 16:16:53 INFO - Running pre-action listener: _resource_record_pre_action 16:16:53 INFO - Running main action method: download_and_extract 16:16:53 INFO - mkdir: /builds/slave/test/build/tests 16:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:53 INFO - https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:16:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:16:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:16:53 INFO - retry: Failed, sleeping 30 seconds before retrying 16:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:17:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:17:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:17:23 INFO - retry: Failed, sleeping 60 seconds before retrying 16:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:18:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:18:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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! 16:18:23 INFO - Caught exception: HTTP Error 404: Not Found 16:18:23 INFO - Caught exception: HTTP Error 404: Not Found 16:18:23 INFO - Caught exception: HTTP Error 404: Not Found 16:18:23 INFO - trying https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:24 INFO - Downloaded 1327 bytes. 16:18:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:18:24 INFO - Using the following test package requirements: 16:18:24 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:18:24 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:18:24 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:18:24 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'jsshell-linux-x86_64.zip'], 16:18:24 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:18:24 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:18:24 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:18:24 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:18:24 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:18:24 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:18:24 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:18:24 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:18:24 INFO - https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:18:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:18:24 INFO - retry: Failed, sleeping 30 seconds before retrying 16:18:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:18:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:18:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:18:54 INFO - retry: Failed, sleeping 60 seconds before retrying 16:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:19:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:19:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:19:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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! 16:19:54 INFO - Caught exception: HTTP Error 404: Not Found 16:19:54 INFO - Caught exception: HTTP Error 404: Not Found 16:19:54 INFO - Caught exception: HTTP Error 404: Not Found 16:19:54 INFO - trying https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:19:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:19:55 INFO - Downloaded 21774448 bytes. 16:19:55 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'] 16:19:55 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 16:19:56 INFO - Return code: 0 16:19:56 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 16:19:56 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')]} 16:19:56 INFO - https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:19:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:19:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:19:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:19:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:19:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:19:56 INFO - retry: Failed, sleeping 30 seconds before retrying 16:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:20:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:20:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:20:26 INFO - retry: Failed, sleeping 60 seconds before retrying 16:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:21:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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! 16:21:26 INFO - Caught exception: HTTP Error 404: Not Found 16:21:26 INFO - Caught exception: HTTP Error 404: Not Found 16:21:26 INFO - Caught exception: HTTP Error 404: Not Found 16:21:26 INFO - trying https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:27 INFO - Downloaded 21774448 bytes. 16:21:27 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'] 16:21:27 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 16:21:28 INFO - Return code: 0 16:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:21:28 INFO - https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:21:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:21:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:21:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:21:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:21:28 INFO - retry: Failed, sleeping 30 seconds before retrying 16:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:21:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:21:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:21:58 INFO - retry: Failed, sleeping 60 seconds before retrying 16:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:22:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:22:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:22:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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! 16:22:58 INFO - Caught exception: HTTP Error 404: Not Found 16:22:58 INFO - Caught exception: HTTP Error 404: Not Found 16:22:58 INFO - Caught exception: HTTP Error 404: Not Found 16:22:58 INFO - trying https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:22:59 INFO - Downloaded 14413762 bytes. 16:22:59 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'] 16:22:59 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 16:22:59 INFO - Return code: 0 16:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:59 INFO - https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:22:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:22:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:22:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:22:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:22:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:22:59 INFO - retry: Failed, sleeping 30 seconds before retrying 16:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:23:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:23:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:23:29 INFO - retry: Failed, sleeping 60 seconds before retrying 16:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:24:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:24:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:24:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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! 16:24:29 INFO - Caught exception: HTTP Error 404: Not Found 16:24:29 INFO - Caught exception: HTTP Error 404: Not Found 16:24:29 INFO - Caught exception: HTTP Error 404: Not Found 16:24:29 INFO - trying https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:24:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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 16:24:31 INFO - Downloaded 56798911 bytes. 16:24:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:24:31 INFO - mkdir: /builds/slave/test/properties 16:24:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:31 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:31 INFO - Contents: 16:24:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:24:31 INFO - Running post-action listener: _resource_record_post_action 16:24:31 INFO - Running post-action listener: set_extra_try_arguments 16:24:31 INFO - [mozharness: 2016-10-24 23:24:31.898337Z] Finished download-and-extract step (success) 16:24:31 INFO - [mozharness: 2016-10-24 23:24:31.898570Z] Running populate-webroot step. 16:24:31 INFO - Running pre-action listener: _resource_record_pre_action 16:24:31 INFO - Running main action method: populate_webroot 16:24:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:24:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:24:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:24:31 INFO - '--tppagecycles', 16:24:31 INFO - '7'], 16:24:31 INFO - 'tests': ['tsvgm']}}, 16:24:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:24:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:24:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['damp', 'tps']}, 16:24:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'tests': ['damp', 'tps']}, 16:24:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['dromaeo_dom']}, 16:24:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['basic_compositor_video']}, 16:24:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:24:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['a11yr', 16:24:31 INFO - 'ts_paint', 16:24:31 INFO - 'tpaint', 16:24:31 INFO - 'sessionrestore', 16:24:31 INFO - 'sessionrestore_no_auto_restore', 16:24:31 INFO - 'tabpaint']}, 16:24:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['tsvgx', 16:24:31 INFO - 'tsvgr_opacity', 16:24:31 INFO - 'tart', 16:24:31 INFO - 'tscrollx', 16:24:31 INFO - 'cart']}, 16:24:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:31 INFO - 'tsvgr_opacity', 16:24:31 INFO - 'tart', 16:24:31 INFO - 'tscrollx', 16:24:31 INFO - 'cart']}, 16:24:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'talos_options': ['--disable-e10s'], 16:24:31 INFO - 'tests': ['tp5o']}, 16:24:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'tests': ['tp5o']}, 16:24:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'talos_options': ['--disable-e10s', 16:24:31 INFO - '--xperf_path', 16:24:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:31 INFO - 'tests': ['tp5n']}, 16:24:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:31 INFO - 'talos_options': ['--xperf_path', 16:24:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:31 INFO - 'tests': ['tp5n']}}, 16:24:31 INFO - 'talos.zip': {'path': '', 16:24:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:24:31 INFO - Running post-action listener: _resource_record_post_action 16:24:31 INFO - [mozharness: 2016-10-24 23:24:31.917133Z] Finished populate-webroot step (success) 16:24:31 INFO - [mozharness: 2016-10-24 23:24:31.917222Z] Running create-virtualenv step. 16:24:31 INFO - Running pre-action listener: _resource_record_pre_action 16:24:31 INFO - Running main action method: create_virtualenv 16:24:31 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:31 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 16:24:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:24:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:32 INFO - Using real prefix '/usr' 16:24:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:32 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:34 INFO - Installing pip.................done. 16:24:34 INFO - Return code: 0 16:24:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:24:34 INFO - Using env: {'DISPLAY': ':0', 16:24:34 INFO - 'HOME': '/home/cltbld', 16:24:34 INFO - 'LANG': 'en_US.UTF-8', 16:24:34 INFO - 'LANGUAGE': 'en_US:en', 16:24:34 INFO - 'LOGNAME': 'cltbld', 16:24:34 INFO - 'MAIL': '/var/mail/cltbld', 16:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:34 INFO - 'MOZ_NO_REMOTE': '1', 16:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:34 INFO - 'NO_EM_RESTART': '1', 16:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:34 INFO - 'PWD': '/builds/slave/test', 16:24:34 INFO - 'SHELL': '/bin/bash', 16:24:34 INFO - 'SHLVL': '1', 16:24:34 INFO - 'TERM': 'linux', 16:24:34 INFO - 'TMOUT': '86400', 16:24:34 INFO - 'USER': 'cltbld', 16:24:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:34 INFO - '_': '/tools/buildbot/bin/python'} 16:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:34 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 16:24:34 INFO - Cleaning up... 16:24:34 INFO - Return code: 0 16:24:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:24:34 INFO - Using env: {'DISPLAY': ':0', 16:24:34 INFO - 'HOME': '/home/cltbld', 16:24:34 INFO - 'LANG': 'en_US.UTF-8', 16:24:34 INFO - 'LANGUAGE': 'en_US:en', 16:24:34 INFO - 'LOGNAME': 'cltbld', 16:24:34 INFO - 'MAIL': '/var/mail/cltbld', 16:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:34 INFO - 'MOZ_NO_REMOTE': '1', 16:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:34 INFO - 'NO_EM_RESTART': '1', 16:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:34 INFO - 'PWD': '/builds/slave/test', 16:24:34 INFO - 'SHELL': '/bin/bash', 16:24:34 INFO - 'SHLVL': '1', 16:24:34 INFO - 'TERM': 'linux', 16:24:34 INFO - 'TMOUT': '86400', 16:24:34 INFO - 'USER': 'cltbld', 16:24:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:34 INFO - '_': '/tools/buildbot/bin/python'} 16:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:34 INFO - Downloading/unpacking psutil>=3.1.1 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:36 INFO - Installing collected packages: psutil 16:24:36 INFO - Running setup.py install for psutil 16:24:37 INFO - building 'psutil._psutil_linux' extension 16:24:37 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 16:24:37 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 16:24:37 INFO - building 'psutil._psutil_posix' extension 16:24:37 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 16:24:37 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 16:24:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:37 INFO - Successfully installed psutil 16:24:37 INFO - Cleaning up... 16:24:37 INFO - Return code: 0 16:24:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:24:37 INFO - Using env: {'DISPLAY': ':0', 16:24:37 INFO - 'HOME': '/home/cltbld', 16:24:37 INFO - 'LANG': 'en_US.UTF-8', 16:24:37 INFO - 'LANGUAGE': 'en_US:en', 16:24:37 INFO - 'LOGNAME': 'cltbld', 16:24:37 INFO - 'MAIL': '/var/mail/cltbld', 16:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:37 INFO - 'MOZ_NO_REMOTE': '1', 16:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:37 INFO - 'NO_EM_RESTART': '1', 16:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:37 INFO - 'PWD': '/builds/slave/test', 16:24:37 INFO - 'SHELL': '/bin/bash', 16:24:37 INFO - 'SHLVL': '1', 16:24:37 INFO - 'TERM': 'linux', 16:24:37 INFO - 'TMOUT': '86400', 16:24:37 INFO - 'USER': 'cltbld', 16:24:37 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:37 INFO - '_': '/tools/buildbot/bin/python'} 16:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:24:39 INFO - Installing collected packages: mozsystemmonitor 16:24:39 INFO - Running setup.py install for mozsystemmonitor 16:24:39 INFO - Successfully installed mozsystemmonitor 16:24:39 INFO - Cleaning up... 16:24:39 INFO - Return code: 0 16:24:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:24:39 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')]} 16:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:39 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')]} 16:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:39 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:39 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 16:24:39 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 16:24:39 INFO - Using env: {'DISPLAY': ':0', 16:24:39 INFO - 'HOME': '/home/cltbld', 16:24:39 INFO - 'LANG': 'en_US.UTF-8', 16:24:39 INFO - 'LANGUAGE': 'en_US:en', 16:24:39 INFO - 'LOGNAME': 'cltbld', 16:24:39 INFO - 'MAIL': '/var/mail/cltbld', 16:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:39 INFO - 'MOZ_NO_REMOTE': '1', 16:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:39 INFO - 'NO_EM_RESTART': '1', 16:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:39 INFO - 'PWD': '/builds/slave/test', 16:24:39 INFO - 'SHELL': '/bin/bash', 16:24:39 INFO - 'SHLVL': '1', 16:24:39 INFO - 'TERM': 'linux', 16:24:39 INFO - 'TMOUT': '86400', 16:24:39 INFO - 'USER': 'cltbld', 16:24:39 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:39 INFO - '_': '/tools/buildbot/bin/python'} 16:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:24:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:24:41 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 16:24:41 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 16:24:41 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 16:24:41 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 16:24:41 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 16:24:41 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 16:24:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:24:42 INFO - warning: no files found matching '*.txt' 16:24:42 INFO - no previously-included directories found matching 'build' 16:24:42 INFO - no previously-included directories found matching 'dist' 16:24:42 INFO - no previously-included directories found matching '.git*' 16:24:42 INFO - Installing collected packages: jsonschema, functools32 16:24:42 INFO - Running setup.py install for functools32 16:24:42 INFO - warning: no files found matching '*.txt' 16:24:42 INFO - no previously-included directories found matching 'build' 16:24:42 INFO - no previously-included directories found matching 'dist' 16:24:42 INFO - no previously-included directories found matching '.git*' 16:24:42 INFO - Successfully installed jsonschema functools32 16:24:42 INFO - Cleaning up... 16:24:42 INFO - Return code: 0 16:24:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24: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')]} 16:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:42 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:42 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 16:24:42 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 16:24:42 INFO - Using env: {'DISPLAY': ':0', 16:24:42 INFO - 'HOME': '/home/cltbld', 16:24:42 INFO - 'LANG': 'en_US.UTF-8', 16:24:42 INFO - 'LANGUAGE': 'en_US:en', 16:24:42 INFO - 'LOGNAME': 'cltbld', 16:24:42 INFO - 'MAIL': '/var/mail/cltbld', 16:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:42 INFO - 'MOZ_NO_REMOTE': '1', 16:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:42 INFO - 'NO_EM_RESTART': '1', 16:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:42 INFO - 'PWD': '/builds/slave/test', 16:24:42 INFO - 'SHELL': '/bin/bash', 16:24:42 INFO - 'SHLVL': '1', 16:24:42 INFO - 'TERM': 'linux', 16:24:42 INFO - 'TMOUT': '86400', 16:24:42 INFO - 'USER': 'cltbld', 16:24:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:42 INFO - '_': '/tools/buildbot/bin/python'} 16:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:24:42 INFO - Cleaning up... 16:24:42 INFO - Return code: 0 16:24:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24: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')]} 16:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:42 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:42 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 16:24:42 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 16:24:42 INFO - Using env: {'DISPLAY': ':0', 16:24:42 INFO - 'HOME': '/home/cltbld', 16:24:42 INFO - 'LANG': 'en_US.UTF-8', 16:24:42 INFO - 'LANGUAGE': 'en_US:en', 16:24:42 INFO - 'LOGNAME': 'cltbld', 16:24:42 INFO - 'MAIL': '/var/mail/cltbld', 16:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:42 INFO - 'MOZ_NO_REMOTE': '1', 16:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:42 INFO - 'NO_EM_RESTART': '1', 16:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:42 INFO - 'PWD': '/builds/slave/test', 16:24:42 INFO - 'SHELL': '/bin/bash', 16:24:42 INFO - 'SHLVL': '1', 16:24:42 INFO - 'TERM': 'linux', 16:24:42 INFO - 'TMOUT': '86400', 16:24:42 INFO - 'USER': 'cltbld', 16:24:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:42 INFO - '_': '/tools/buildbot/bin/python'} 16:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:24:42 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 16:24:42 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 16:24:42 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 16:24:42 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 16:24:42 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 16:24:42 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 16:24:44 INFO - Downloading blobuploader-1.2.4.tar.gz 16:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:24:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:24:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:45 INFO - Downloading docopt-0.6.1.tar.gz 16:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:24:45 INFO - Installing collected packages: blobuploader, requests, docopt 16:24:45 INFO - Running setup.py install for blobuploader 16:24:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:24:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:24:45 INFO - Running setup.py install for requests 16:24:46 INFO - Running setup.py install for docopt 16:24:46 INFO - Successfully installed blobuploader requests docopt 16:24:46 INFO - Cleaning up... 16:24:46 INFO - Return code: 0 16:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:46 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:46 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 16:24:46 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 16:24:46 INFO - Using env: {'DISPLAY': ':0', 16:24:46 INFO - 'HOME': '/home/cltbld', 16:24:46 INFO - 'LANG': 'en_US.UTF-8', 16:24:46 INFO - 'LANGUAGE': 'en_US:en', 16:24:46 INFO - 'LOGNAME': 'cltbld', 16:24:46 INFO - 'MAIL': '/var/mail/cltbld', 16:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:46 INFO - 'MOZ_NO_REMOTE': '1', 16:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:46 INFO - 'NO_EM_RESTART': '1', 16:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:46 INFO - 'PWD': '/builds/slave/test', 16:24:46 INFO - 'SHELL': '/bin/bash', 16:24:46 INFO - 'SHLVL': '1', 16:24:46 INFO - 'TERM': 'linux', 16:24:46 INFO - 'TMOUT': '86400', 16:24:46 INFO - 'USER': 'cltbld', 16:24:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:46 INFO - '_': '/tools/buildbot/bin/python'} 16:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:46 INFO - Running setup.py (path:/tmp/pip-S7d1zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:46 INFO - Running setup.py (path:/tmp/pip-xjSpwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:46 INFO - Running setup.py (path:/tmp/pip-Eo9Une-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:46 INFO - Running setup.py (path:/tmp/pip-YwglCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:46 INFO - Running setup.py (path:/tmp/pip-1JmoSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:46 INFO - Running setup.py (path:/tmp/pip-e1RGX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:46 INFO - Running setup.py (path:/tmp/pip-_cLfUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:47 INFO - Running setup.py (path:/tmp/pip-vZsfhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:47 INFO - Running setup.py (path:/tmp/pip-NEe4EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:47 INFO - Running setup.py (path:/tmp/pip-tCk6BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:47 INFO - Running setup.py (path:/tmp/pip-aeZ361-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:47 INFO - Running setup.py (path:/tmp/pip-kHTRJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:47 INFO - Running setup.py (path:/tmp/pip-onAbX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:47 INFO - Running setup.py (path:/tmp/pip-V7RkJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:47 INFO - Running setup.py (path:/tmp/pip-tJU0ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:47 INFO - Running setup.py (path:/tmp/pip-XpMBcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:47 INFO - Running setup.py (path:/tmp/pip-_D99kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:24:47 INFO - Running setup.py install for manifestparser 16:24:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:24:47 INFO - Running setup.py install for mozcrash 16:24:47 INFO - Running setup.py install for mozdebug 16:24:48 INFO - Running setup.py install for mozdevice 16:24:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for mozfile 16:24:48 INFO - Running setup.py install for mozhttpd 16:24:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for mozinfo 16:24:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for mozInstall 16:24:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for mozleak 16:24:48 INFO - Running setup.py install for mozlog 16:24:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for moznetwork 16:24:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:24:48 INFO - Running setup.py install for mozprocess 16:24:49 INFO - Running setup.py install for mozprofile 16:24:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:24:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:24:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:24:49 INFO - Running setup.py install for mozrunner 16:24:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:24:49 INFO - Running setup.py install for mozscreenshot 16:24:49 INFO - Running setup.py install for moztest 16:24:49 INFO - Running setup.py install for mozversion 16:24:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:24:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:24:49 INFO - Cleaning up... 16:24:49 INFO - Return code: 0 16:24:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24: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')]} 16:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:49 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:49 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 16:24:49 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 16:24:49 INFO - Using env: {'DISPLAY': ':0', 16:24:49 INFO - 'HOME': '/home/cltbld', 16:24:49 INFO - 'LANG': 'en_US.UTF-8', 16:24:49 INFO - 'LANGUAGE': 'en_US:en', 16:24:49 INFO - 'LOGNAME': 'cltbld', 16:24:49 INFO - 'MAIL': '/var/mail/cltbld', 16:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:49 INFO - 'MOZ_NO_REMOTE': '1', 16:24:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:49 INFO - 'NO_EM_RESTART': '1', 16:24:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:49 INFO - 'PWD': '/builds/slave/test', 16:24:49 INFO - 'SHELL': '/bin/bash', 16:24:49 INFO - 'SHLVL': '1', 16:24:49 INFO - 'TERM': 'linux', 16:24:49 INFO - 'TMOUT': '86400', 16:24:49 INFO - 'USER': 'cltbld', 16:24:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:49 INFO - '_': '/tools/buildbot/bin/python'} 16:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:49 INFO - Running setup.py (path:/tmp/pip-mB6TFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:49 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)) 16:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:49 INFO - Running setup.py (path:/tmp/pip-RmXQFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:49 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)) 16:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:49 INFO - Running setup.py (path:/tmp/pip-rpqfbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:49 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)) 16:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:49 INFO - Running setup.py (path:/tmp/pip-BldFv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:50 INFO - Running setup.py (path:/tmp/pip-X2MfpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:50 INFO - Running setup.py (path:/tmp/pip-QDcTFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:50 INFO - Running setup.py (path:/tmp/pip-xTUlrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:50 INFO - Running setup.py (path:/tmp/pip-aignkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:50 INFO - Running setup.py (path:/tmp/pip-NWqB9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:50 INFO - Running setup.py (path:/tmp/pip-FSLX5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:50 INFO - Running setup.py (path:/tmp/pip-dabkig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:50 INFO - Running setup.py (path:/tmp/pip-rXdXLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:50 INFO - Running setup.py (path:/tmp/pip-_XXdAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:50 INFO - Running setup.py (path:/tmp/pip-x4ISN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:50 INFO - Running setup.py (path:/tmp/pip-7HjEiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:50 INFO - Running setup.py (path:/tmp/pip-EImA7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:50 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)) 16:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:50 INFO - Running setup.py (path:/tmp/pip-U51qh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:50 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)) 16:24:50 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)) 16:24:50 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)) 16:24:50 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)) 16:24:50 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)) 16:24:50 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)) 16:24:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:24:51 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 16:24:51 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 16:24:51 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 16:24:51 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 16:24:51 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 16:24:51 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 16:24:52 INFO - Downloading blessings-1.6.tar.gz 16:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:24:52 INFO - Installing collected packages: blessings 16:24:52 INFO - Running setup.py install for blessings 16:24:53 INFO - Successfully installed blessings 16:24:53 INFO - Cleaning up... 16:24:53 INFO - Return code: 0 16:24:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:53 INFO - Reading from file tmpfile_stdout 16:24:53 INFO - Current package versions: 16:24:53 INFO - argparse == 1.2.1 16:24:53 INFO - blessings == 1.6 16:24:53 INFO - blobuploader == 1.2.4 16:24:53 INFO - docopt == 0.6.1 16:24:53 INFO - functools32 == 3.2.3-2 16:24:53 INFO - jsonschema == 2.5.1 16:24:53 INFO - manifestparser == 1.1 16:24:53 INFO - mozInstall == 1.12 16:24:53 INFO - mozcrash == 0.17 16:24:53 INFO - mozdebug == 0.1 16:24:53 INFO - mozdevice == 0.48 16:24:53 INFO - mozfile == 1.2 16:24:53 INFO - mozhttpd == 0.7 16:24:53 INFO - mozinfo == 0.9 16:24:53 INFO - mozleak == 0.1 16:24:53 INFO - mozlog == 3.3 16:24:53 INFO - moznetwork == 0.27 16:24:53 INFO - mozprocess == 0.23 16:24:53 INFO - mozprofile == 0.28 16:24:53 INFO - mozrunner == 6.12 16:24:53 INFO - mozscreenshot == 0.1 16:24:53 INFO - mozsystemmonitor == 0.3 16:24:53 INFO - moztest == 0.7 16:24:53 INFO - mozversion == 1.4 16:24:53 INFO - psutil == 3.1.1 16:24:53 INFO - requests == 1.2.3 16:24:53 INFO - wsgiref == 0.1.2 16:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:53 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:53 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 16:24:53 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 16:24:53 INFO - Using env: {'DISPLAY': ':0', 16:24:53 INFO - 'HOME': '/home/cltbld', 16:24:53 INFO - 'LANG': 'en_US.UTF-8', 16:24:53 INFO - 'LANGUAGE': 'en_US:en', 16:24:53 INFO - 'LOGNAME': 'cltbld', 16:24:53 INFO - 'MAIL': '/var/mail/cltbld', 16:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:53 INFO - 'MOZ_NO_REMOTE': '1', 16:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:53 INFO - 'NO_EM_RESTART': '1', 16:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:53 INFO - 'PWD': '/builds/slave/test', 16:24:53 INFO - 'SHELL': '/bin/bash', 16:24:53 INFO - 'SHLVL': '1', 16:24:53 INFO - 'TERM': 'linux', 16:24:53 INFO - 'TMOUT': '86400', 16:24:53 INFO - 'USER': 'cltbld', 16:24:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:53 INFO - '_': '/tools/buildbot/bin/python'} 16:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 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)) 16:24:53 INFO - Cleaning up... 16:24:53 INFO - Return code: 0 16:24:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:53 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 0x1d7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2040180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', '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 16:24:53 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 16:24:53 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 16:24:53 INFO - Using env: {'DISPLAY': ':0', 16:24:53 INFO - 'HOME': '/home/cltbld', 16:24:53 INFO - 'LANG': 'en_US.UTF-8', 16:24:53 INFO - 'LANGUAGE': 'en_US:en', 16:24:53 INFO - 'LOGNAME': 'cltbld', 16:24:53 INFO - 'MAIL': '/var/mail/cltbld', 16:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:53 INFO - 'MOZ_NO_REMOTE': '1', 16:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:53 INFO - 'NO_EM_RESTART': '1', 16:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:53 INFO - 'PWD': '/builds/slave/test', 16:24:53 INFO - 'SHELL': '/bin/bash', 16:24:53 INFO - 'SHLVL': '1', 16:24:53 INFO - 'TERM': 'linux', 16:24:53 INFO - 'TMOUT': '86400', 16:24:53 INFO - 'USER': 'cltbld', 16:24:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:53 INFO - '_': '/tools/buildbot/bin/python'} 16:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:24:53 INFO - Cleaning up... 16:24:53 INFO - Return code: 0 16:24:53 INFO - Running post-action listener: _resource_record_post_action 16:24:53 INFO - Running post-action listener: _start_resource_monitoring 16:24:53 INFO - Starting resource monitoring. 16:24:53 INFO - [mozharness: 2016-10-24 23:24:53.847690Z] Finished create-virtualenv step (success) 16:24:53 INFO - [mozharness: 2016-10-24 23:24:53.848122Z] Running install step. 16:24:53 INFO - Running pre-action listener: _resource_record_pre_action 16:24:53 INFO - Running main action method: install 16:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:54 INFO - Reading from file tmpfile_stdout 16:24:54 INFO - Detecting whether we're running mozinstall >=1.0... 16:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:24:54 INFO - Reading from file tmpfile_stdout 16:24:54 INFO - Output received: 16:24:54 INFO - Usage: mozinstall [options] installer 16:24:54 INFO - Options: 16:24:54 INFO - -h, --help show this help message and exit 16:24:54 INFO - -d DEST, --destination=DEST 16:24:54 INFO - Directory to install application into. [default: 16:24:54 INFO - "/builds/slave/test"] 16:24:54 INFO - --app=APP Application being installed. [default: firefox] 16:24:54 INFO - mkdir: /builds/slave/test/build/application 16:24:54 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'] 16:24:54 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 16:25:07 INFO - Reading from file tmpfile_stdout 16:25:07 INFO - Output received: 16:25:07 INFO - /builds/slave/test/build/application/firefox/firefox 16:25:07 INFO - Running post-action listener: _resource_record_post_action 16:25:07 INFO - [mozharness: 2016-10-24 23:25:07.747272Z] Finished install step (success) 16:25:07 INFO - [mozharness: 2016-10-24 23:25:07.747554Z] Running run-tests step. 16:25:07 INFO - Running pre-action listener: _resource_record_pre_action 16:25:07 INFO - Running main action method: run_tests 16:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:25:07 INFO - Python 2.7.3 16:25:07 INFO - Return code: 0 16:25:07 INFO - grabbing minidump binary from tooltool 16:25: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')]} 16:25:07 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 0x203e3b0>, '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 0x203f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x203f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:25:07 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 16:25:07 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 16:25:07 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 16:25:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:25:07 INFO - Return code: 0 16:25:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:25:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:25:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:25:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:25:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:25:07 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/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:25:07 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/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 16:25:07 INFO - Using env: {'DISPLAY': ':0', 16:25:07 INFO - 'HOME': '/home/cltbld', 16:25:07 INFO - 'LANG': 'en_US.UTF-8', 16:25:07 INFO - 'LANGUAGE': 'en_US:en', 16:25:07 INFO - 'LOGNAME': 'cltbld', 16:25:07 INFO - 'MAIL': '/var/mail/cltbld', 16:25:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:25:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:07 INFO - 'MOZ_NO_REMOTE': '1', 16:25:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:07 INFO - 'NO_EM_RESTART': '1', 16:25:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:07 INFO - 'PWD': '/builds/slave/test', 16:25:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:25:07 INFO - 'SHELL': '/bin/bash', 16:25:07 INFO - 'SHLVL': '1', 16:25:07 INFO - 'TERM': 'linux', 16:25:07 INFO - 'TMOUT': '86400', 16:25:07 INFO - 'USER': 'cltbld', 16:25:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280', 16:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:07 INFO - '_': '/tools/buildbot/bin/python'} 16:25:07 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/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:25:08 INFO - mozversion application_buildid: 20161024132223 16:25:08 INFO - mozversion application_changeset: 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 16:25:08 INFO - mozversion application_display_name: Firefox 16:25:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:25:08 INFO - mozversion application_name: Firefox 16:25:08 INFO - mozversion application_remotingname: firefox 16:25:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:25:08 INFO - mozversion application_vendor: Mozilla 16:25:08 INFO - mozversion application_version: 50.0 16:25:08 INFO - mozversion platform_buildid: 20161024132223 16:25:08 INFO - mozversion platform_changeset: 0b0bf76318dc9c5eaf2b8f86bb4132a1d01f7d93 16:25:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:25:08 INFO - mozversion platform_version: 50.0 16:25:08 INFO - using testdate: 1477351508 16:25:08 INFO - actual date: 1477351508 16:25:08 INFO - starting webserver on 'localhost:42581' 16:25:08 INFO - SUITE-START | Running 2 tests 16:25:08 INFO - TEST-START | dromaeo_css 16:25:08 INFO - operating with platform_type : linux_ 16:25:08 INFO - Initialising browser for dromaeo_css test... 16:25:08 INFO - TEST-INFO | started process 5731 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Zqbof/profile http://localhost:42581/getInfo.html) 16:25:12 INFO - PROCESS | 5731 | __metrics Screen width/height:1600/1200 16:25:12 INFO - PROCESS | 5731 | colorDepth:24 16:25:12 INFO - PROCESS | 5731 | Browser inner width/height: 1024/697 16:25:12 INFO - PROCESS | 5731 | __metrics 16:25:12 INFO - PROCESS | 5731 | 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" 16:25:12 INFO - PROCESS | 5731 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:25:13 INFO - PROCESS | 5731 | console.error: PushService: 16:25:13 INFO - PROCESS | 5731 | stateChangeProcessEnqueue: Error transitioning state 16:25:13 INFO - PROCESS | 5731 | UnknownError 16:25:13 INFO - TEST-INFO | 5731: exit 0 16:25:13 INFO - Browser initialized. 16:25:13 INFO - Running cycle 1/1 for dromaeo_css test... 16:25:13 INFO - TEST-INFO | started process 5822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Zqbof/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:25 INFO - PROCESS | 5822 | RSS: Main: 143446016 16:25:25 INFO - PROCESS | 5822 | 16:26:16 INFO - PROCESS | 5822 | 1477351576314 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] 16:28:25 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42581/tests/dromaeo/cssquery-ext.html) 16:28:26 INFO - PROCESS | 5822 | RSS: Main: 264581120 16:28:26 INFO - PROCESS | 5822 | 16:31:28 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-ext.html (next: http://localhost:42581/tests/dromaeo/cssquery-jquery.html) 16:31:29 INFO - PROCESS | 5822 | RSS: Main: 236650496 16:31:29 INFO - PROCESS | 5822 | 16:34:31 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42581/tests/dromaeo/cssquery-mootools.html) 16:34:31 INFO - PROCESS | 5822 | RSS: Main: 175235072 16:34:31 INFO - PROCESS | 5822 | 16:37:33 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42581/tests/dromaeo/cssquery-prototype.html) 16:37:34 INFO - PROCESS | 5822 | RSS: Main: 177135616 16:37:34 INFO - PROCESS | 5822 | 16:40:36 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42581/tests/dromaeo/cssquery-yui.html) 16:40:37 INFO - PROCESS | 5822 | RSS: Main: 173211648 16:40:37 INFO - PROCESS | 5822 | 16:43:39 INFO - PROCESS | 5822 | Cycle 1(1): loaded http://localhost:42581/tests/dromaeo/cssquery-yui.html (next: http://localhost:42581/tests/dromaeo/cssquery-dojo.html) 16:43:40 INFO - PROCESS | 5822 | RSS: Main: 264245248 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | __start_tp_report 16:43:40 INFO - PROCESS | 5822 | _x_x_mozilla_page_load 16:43:40 INFO - PROCESS | 5822 | _x_x_mozilla_page_load_details 16:43:40 INFO - PROCESS | 5822 | |i|pagename|runs| 16:43:40 INFO - PROCESS | 5822 | |0;dojo.html;3227.20933371324;3238.643749187589;3262.543243945848;3272.8527216275265;3304.9339013219737;5544.639598426104;5631.549476041917;5634.915476267859;5640.012997725397;5644.435556444359;5623.212750214963;5666.858328541778;5676.772929082842;5678.943210567893;5688.658680479174;5134.460881607433;5146.794128234867;5152.458991805863;5215.243789650517;5218.034663587248;4933.481984391641;5004.974975125119;5184.688918664892;5233.685978841263;5257.842264732064;4999.075171093341;5041.57146642534;5067.543921047105;5077.5684066854365;5085.593152547787;5086.593072554206;5128.974355128238;5187.766550505236;5189.818356357547;5219.19102539107;4868.902621947576;5080.745962701887;5101.20931255656;5138.10083235436;5160.7419629018395;5343.8396848094235;5436.184572314145;5458.0721277382545;5465.316835395575;5481.890362192734;5866.061430171195;5911.822645320647;5962.105684147369;5967.850803729898;5968.7612495500125;5785.045467497858;5859.589828711992;6004.8498787530225;6013.338532761392;6015.849603759941;5511.22842802008;5545.306836645419;5575.414581468887;5579.972100139514;5616.129499927477;6067.514598832171;6083;6091.817245482643;6094.329623741385;6096.664683442454;5678.744456499468;5781.855453613609;5868.09044598088;5947.940520594739;5961.433663801932;6008.158857759917;6017.788870272515;6036.849078773066;6038.909416358758;6056.273247210363;5938.703064846741;5948.791792287249;5966.970165149233;5970.582059255898;5979.850503737442;5717.885642287131;5900.940990590125;5949.672767997715;5960.225170727777;5964.850878728066;4483.21543729846;4527.207738645724;4586.610138138229;4605.815767369335;4626.074785043005;4074.1647962167685;4093.263212621757;4102.712810103324;4118.835246590163;4140.944059264868;5498.80754173602;5722.599418040781;5739.942600574024;5752.798652047159;5753.079507278899;5919.733611987471;5999.730012149464;6015.699215039231;6039.396060394013;6063.7574497020505;5750.1949727038245;5752.654840709571;5754.942450575524;5771.9711401443565;5780.046292361797;4966.1061009018;5234.869128271747;5236.895262094737;5238;5240;6126.8230438726505;6207.162033125561;6215.34738852423;6294.244690637152;6299.874002520016;6001.06983417571;6018.699065046642;6020.608660437058;6021.217241758543;6061.121137434959;5963.642181469126;6216.502679785516;6263.060540918898;6297.842553936188;6306.527010474325;5808.01263785166;5901.439363260483;5913.172155898264;5954.46409823118;5962.701864906824;6022.45797878193;6027;6029.155918171372;6037.607555508878;6052.485538729257;5538.224648549126;5887.764489420547;5896.941030589639;5897.144913987534;5900.498457631149;5755.53955683536;5832.066869300891;5835.2122463466885;5895.528357731457;5916.556258280732;5854.736536855767;5855.121731740102;5863.912041319469;5864.560157988082;5868.38381969887;5631.4368563145;5816;5838.883222335615;5884.852878678067;5913.674747888907;5588.524975377139;5694.943050569441;5699.344575373885;5723.084306510938;5759.625624334572 16:43:40 INFO - PROCESS | 5822 | |1;ext.html;592.8962431574473;634.2167423232307;659.5350278053972;662.6487961380464;674.7132468700801;34116;45763;47230.291545626846;47340.526594734045;47392.28911566329;47801;48061.51938480614;48227.75886120573;48298.27552586714;48338.75830620851;8743.562821858914;9360.57877395517;9497.430154190735;9514.381565198275;9516.571754271039;11043.392613406259;11289.040431563328;11313.377764222965;11700.473478693437;11701.239419437708;11405.20163588549;11520.19358644891;11522.884771152265;11526.020288275507;11529.365884876328;11357.829632555518;11420.257683250562;11424.543018279343;11431.714207144805;11434.199606027581;11150.776984460346;11236.101111911028;11258.324500529994;11262.493187806569;11267.60563380286;44279.77860110711;44931.32603010957;45054.32418513725;45085;45091.54908450906;3837.846486140551;3866.5746767855794;3970.1861118470642;3975.1056012397266;4053.783864840536;34369.656303436896;36510.63489365099;36576.268474630626;36658.8167059163;36872.81563592165;4792.7843247053615;4816.8554943351755;4888.266759985995;4902.093112774112;4947.826826061107;413746;415087;415398;416567;418920;70043;70844;70924;71002;71028.64485677538;39737.602623973966;40711;40759.592404076175;42972.140557189305;42979.57020429818;19296.80703192978;19590.118444670024;19609.607807844048;19610.215591376185;19614.70577941332;13699;14550.708985820436;14853.777193342108;14967.925160374129;15084.547463576111;21459.78540214609;22156.33530994073;22190.334289971328;22209.666854997187;22260.332190034325;7933.206679332136;8096.433249672584;8098.959505202436;8204.83590328202;8338.666453341919;956.2156499635078;997.9900200997897;1024.3827021958818;1129.6215767717742;1179.6225207933378;33772.32455350879;34123.829380852934;34343.656563434546;34379.77718938932;34666.48000279998;1200.3218181727295;1204.3917821500086;1243.875612438749;1254.5609036837216;1298.4351806964;17967;18001.459956201335;18119.63760724778;18120.184591693407;18183;670324;672985;675683;676303;682337;63152;63803.36196638067;63915;63951.04073438902;63953.36046639567;1312.173330801608;1406.184413040434;1418.14202407544;1424.3732757586629;1465.545680838922;33938.66061339454;36225.637743622225;36240.81879590585;36343;36375.63624363722;53144;53804.461955381514;53820;53873.461265386846;53885.730571346896;417788;418926;419798;419810;421659;26074.49431927678;27698;27768.722312776612;29730.405391891607;29745.70254297516;51058;52008;52120;52164.87010779366;52198.478015220884;43521.78239108784;44797;44872.77563612161;45374.77312613548;45427.77286113548;44444.55555444534;44981.775091124335;45406.31890521579;45471;45576.45013041858;43516.347254791835;44297.55702442934;44506.777466112464;44544.33183502315;44671.55328446804;37454.25091498209;39366.80316598398;39570;40068.1986360277;40691.389629156176;29668.258293542833;29730.851345743133;30092.849535752182;30163.396732064797;30195.849020754755 16:43:40 INFO - PROCESS | 5822 | |2;jquery.html;385.57394079542087;386.551600143833;399.8180827723385;400.56538655558717;420.5605142625956;13913.443462261497;19128.617427651458;20884.06021729022;20918.477038074056;22242.666360004612;19127.139278732455;19999.700004499944;20034.89982550089;20321.796782032176;20424.183032678677;5570.470805273502;5695.03184458643;5706.714664266791;5707.571932105088;5709.343425506078;4202.642775364083;5649;5688.11834165705;5688.829335119933;5689.402612725667;6565.835854103638;6768.289329620393;6862.416694580967;6917.308269173092;6929.09921710177;6251.718672659718;6310.905336419957;6316.336784637593;6687.5653082549725;6897.965510172442;6010.429009244115;6021.638701677914;6154.415330543591;6154.907676384858;6161.876762464771;14749.852501474956;15187.392504299814;15200.695986080327;15201.163935983517;17785;2340.66060421238;2552.1577879299784;2929.9707002929913;3144.3239703463637;3222.5810644616286;10784.739160144994;11261.98642122213;14271;14416.774574161103;14806.185659788707;1915.9425217243504;1971.8619696621242;2025.1899240303849;2032.9898350508154;2057.68105943579;60698.304344806216;65990;73138.63430682865;73533.26466735317;80332.59833700911;11126.554937802397;11371.862813718562;11591.942040289914;11773.17587768848;12213;16794.74039447022;17047.062411567484;17078.914605426893;17081.48755537336;17092.145392730316;8617.224449799549;8899.555022248862;9010.684626038057;9120.179183873483;9250.86123708145;8958.86561701575;9106.49914254709;9117.270618350649;9241.768955776159;9247.861282080774;4936.185529387611;5075.720835354017;5101.591872650179;5174.722517035826;5231.476852314814;2076.356329537818;2077.5588683297065;2115.0905110802196;2153.12798316682;2187.9453013674784;469.5984932882405;483.7508683028243;928.8281667891462;1099.3239157917983;1100.9009189173014;12478.689737577604;12736;12897.742045159044;12915.483380664857;12985.35073246334;509.1319300592481;520.217073304674;526.3157894736827;535.2159086937655;548.8627843039241;6912.343327383891;6999.790006299819;7104.6447677615915;7115.359617634438;7183.964080179566;537539;547673;548732;554212;554550;93625.58711697553;94627.05372946183;94808.43865061118;116399;118195.40902295434;1064.164630764846;1074.8065348237235;1109.5229051507768;1110.6612483192703;1112.5327362507605;18668.7199692002;19061.332853350064;19181.904090479456;19186;19612.215511379385;35652.46521302131;45349;45924.770376147906;46415.303770444145;47115;64862.35137648752;68894.65552672405;69206.307936922;69340;69512;9154.954225228832;12249.816252756395;13535.25556094385;13742.312884355542;14054.718905621627;14415;14616.342264598332;14932.328045237773;15350.846491534941;15382.692346153239;13342.13276137048;13360.665983350495;13366.660180902825;13582.117162384413;13915.791263131263;12663.556775512812;13266.673332666656;13298.202107873558;13530;13600.727985440039;13173.341332933123;13297.135686180367;13657.931710341385;13831.654208644864;13896.444142234197;13281.269530175932;13330.600081997556;13404.865951340362;13461.192328460324;13466.865331346562;6110.877782444415;13353;13407.597772066854;13524.864751352361;13588.59234222975 16:43:40 INFO - PROCESS | 5822 | |3;mootools.html;1357.9388927498262;1462.3660204392083;1499.4976682811257;1642.8275031121732;1848.713449415341;8545.871811922818;9411.858822117674;9745.902540974588;9752.902470975288;9998.200143988483;9762.853557196648;9856.310058295905;9862.30963832532;9870.161036311903;9871.703848884526;2532.734062923394;2560.5519034169038;2566;2581.6901971763414;2585.146901522493;2478.8388754730886;2520.5841036229;2538.6826646669165;2541.326548464649;2542.7965762739027;2471.45627961849;2486.589712697403;2533.1513942829174;2535.632333311669;2537.0612873236837;2413.336332508565;2423.5879900416885;2432.927012189637;2475.8266921315517;2476.628505724138;2360.893759780814;2395.101836811196;2404.927852164438;2412.3848418653306;2459.02868366994;9559.283053770994;9754.170895473822;9819.950900245452;9821.263405244636;9831.360961537477;5805.564582656318;5996.220491336098;6026;6026.397360263983;6045.274567051938;3596.496490491333;3649.7080233581514;3657.414813629833;3666.4866918631415;3701.6298370163036;1216.318861437589;1229.1088960503564;1273.7898246789268;1303.6805982534245;1320;14176;14332.928335358256;14348.282585870666;14354;14379.065360751518;4855.757212139379;4897.118518666674;4981.90036199274;5073.923891141636;5075.949240507622;10866.72833179161;10871.619493317854;10873.728156795985;10873.891261087447;10881.075108615856;5715.199872017921;5748.367679555246;5749.712514374264;5757.395473475309;5760.107183386582;4738.597219236334;5150.48495150482;5252.842414727565;5273.3935597406025;5327.680339179662;4132.621383335403;4148;4236.555161708038;4247.766372849461;4267.4025636410925;1236.7031912341154;1243.7325974915436;1243.7636848998661;1270.9618711438673;1284.6274580371587;930.9805762689844;990.4552496127101;1001.3140998416109;1005.2410430125209;1033.8707661542308;6396.2964073951825;6489.675516224264;6681.33186681328;6694.397504224643;6856.5200435969;476.81880885263377;484.64862974343316;488.060483569128;504.1681225977094;513.8689634143269;4402.493713222956;4485.394471746337;4487.820487180542;4585.495595484494;4585.72485650862;12079.516819327304;12612.180208286432;12615.30615816139;12622.179558328678;12622.81065784014;11542.307461552165;12148.45331960064;12149.08881833866;12511.249325040526;12512.37438128108;8688.174623410765;8831.161039701217;8879.955600222087;8881.42270752399;8887.022427532966;6415.582987105823;6481.222253329631;6616.040674102324;6642.501812363996;6689.063531105552;11312.94343528277;11391.544338226378;11442.656720298404;11695.532178712756;11882.643520694393;11447.313161210353;11678.766424671629;11890.940545297219;11914.940425297818;12063.939680301894;7804.765857024298;8009.759707208793;8020.438569300093;8546.102659220942;8769.956150219208;8745.737627871174;9267.629294828132;9519.381240219363;9590.472524011246;9860.556274967501;8417.957910210409;8560.48637081777;8592.699255526026;8618.612162452579;8692.608832602675;8545.487270763773;8555.57222138903;8573.22840944318;8659.263962563253;8681.782955426164;7530.849383012419;7557.924420755942;7597.240275972358;7603.391728661804;7604.8479030417975;5585.245991791056;5675.233843431084;5696.344920334214;5764.42355764437;5846;5732.168835518743;5775.942240577709;5872.882542349043;5887.939221272692;5923.614965027259 16:43:40 INFO - PROCESS | 5822 | |4;prototype.html;356.77344885997394;365.2074997255954;365.8170914542729;366.50338790938275;369.3481006024368;5513.145462453316;5515.61390702651;5523.723813809309;5527.2538207341995;5584.301962254718;5274.4725527447235;5429.647072940256;5509.807156749514;5510.669359838413;5538.75075621597;4342.652587792969;4357.411749413836;4363.454568178978;4369.798305465991;4384.912301753963;4346.174226896896;4353.390525326457;4376.496702879178;4381.605655491006;4422.292433210687;4318.244307246235;4336.176126535948;4336.6530677545725;4390;4390.495093064291;4291.6566674665955;4324.978375108104;4327.545607711177;4327.697061205701;4345.217860785057;3901.200253947934;3904.2777086239134;3904.668103211202;3913.2760439318818;3915.5888631693833;5278.709670968095;5304.38999515053;5344.43883392246;5378.623496355257;5384.1923711443205;4847.248676455155;4937.703737775709;4986.376702912136;5013.498650134994;5032.547070763649;5626;5672.461116193955;5680.176374425727;5724;5772.249607550992;4296.31480324797;4310.698251122422;4311.956880431187;4317.266064768965;4322.33590067823;5583.274174357308;5703.942960570383;5887.587868849182;5889.050919914499;5898.469137777621;4328.783560821947;4341.826326946887;4343;4346.195953748566;4366.519682834885;4388.341748737715;4412.514623391425;4425.889352766142;4428.98133429313;4446.888827779267;4849.369581954324;4922.507749225048;4952.331435256195;4958.628102892298;4986.152354099811;5632.831015069554;5685.403032681592;5692.715364231772;5705.62913410627;5712.228849105318;1522.276918463743;1538.4615384615295;1540.322258206408;1545.5286137727876;1548;964.5514835601339;995.875515560555;998.7652901568125;1002.6490728245202;1029.3463650581784;4911.45973942866;4924.507549245047;4977.90044199114;5043.713852967469;5062.670926389773;5186.533212010937;5208.723862653598;5228.503292187236;5230.790768369298;5236.450172731885;4820;4850.369451971292;4880.316755654211;5008.499150084962;5031.77357018935;2716.8777405016426;2728.863556822151;2731.726827317252;2748.694370174151;2753.931151721183;5360.678359298455;5385.784568617211;5414.7834086636885;5437.8368648940595;5458.2904222451625;5029.547340739351;5191.636585439059;5230.783068412975;5245.763940622681;5313.1764576490705;5323.760430780624;5331.893362132736;5339.332583427072;5375;5386.034097554364;5140.974295128501;5161.7677204526635;5181.35233095863;5220.738963051908;5223.294855194652;4814.494478079891;5070.51830076157;5082.263071854634;5091.338126043591;5095.974520127375;5367.302250707383;5896.6756828374855;5967.8959392512525;6079.966405710951;6102.755889764359;4339.654707040828;4361.694681372273;4390.1878152541885;4398.384226208397;4418.6139484677915;5622.578306626938;5888.262962426009;5924.9703751480965;5929.911051334319;5937.228160339128;5702.230198923092;5713.257276554022;5716.3140423147825;5735.369109398046;5738.770449181986;5661.773529058792;5678.68767217807;5713.7428815704225;5718.456746609066;5722.484976352148;5479.369872464558;5665.830025099253;5700.059490184239;5705.600607957404;5705.7147142643535;5722.456366645162;5740.598158128891;5751.856203594776;5755.5683323750045;5778.797742079007;5613.915791263053;5615.831525054255;5632.760792625616;5672.858178545569;5683.403242659461 16:43:40 INFO - PROCESS | 5822 | |5;yui.html;789.9684012639494;803.987940180897;815.2947700239291;839.65574114613;866.7399780065979;15980;16571.751423728652;16574.66850662986;16581.91709041456;16588.917055414735;15335;15623.140727259995;15624;15647.530574082768;15664.84335156648;699.1854489519703;826.3719573124439;834.1200033964166;850.6427300533791;856.0797143071193;842.0947481457428;842.6755699055847;844.4004756622805;844.4426678392263;845.243507061183;900.3967341880956;901.3104974694364;901.4861528928514;901.7430032440756;902.0798785239052;901.197933838884;901.9774505637346;903.1465265324272;903.4127816919022;903.4940433357341;660.0297562582996;660.3726459863101;662.3707477896018;662.6653539962289;662.811098836834;15145.924270378688;15161.166135862522;15168.848311516855;15208;15210.46763363277;15994.600134996603;16444.39567893232;16620.168991550494;16899.662006759794;17182;13003.349832508431;13014.544490942772;13093.279869607168;13444;13647.658808529766;4087.509498860156;4100.91798164038;4135.5657655946;4183.644390226835;4212.49450065991;72179.63910180469;72951.27048729498;89517;91042.5447872763;93078;11599.072074234042;12218.633440996784;12223.1443798935;12572.622821315375;12596.370181490889;20270.898645506677;22820;22848.65727014096;22852.4286892829;22887.970041347846;8544.572771361407;9021.052789457144;9167.77080572991;9194.31042671789;9214.493202873906;4976.377952755905;5117.897642047213;5191.922121168185;5299.178627312773;5447.809326673548;4069.97965010179;4199.664026877843;4215.894602634959;4224.2185195738275;4239.51245606753;702.8699690557263;705.5202462325668;709.0498731699547;709.2694524639527;733.9596322202224;641.7112299465166;689.2349492063805;817.5013241922422;834.995825020871;840.9495430274203;9995.400275983464;10706.304090234109;10901.509432075576;11396.829047564293;11468.369239691898;730.9656835577598;747.2639450141606;758.4084414156966;798.8282519258387;834.1241696218946;4795.20879054959;4857.465678775332;4966.379202599675;5075.137226671474;5188.792448302026;122301;123474;126006;134607;134825.32587337;107382.46308768407;108766.45616772024;109699;109869;111871.44064279627;89954.55022724844;96035;99101;100050;100394;9998.850017249602;10240.487975601041;10530.736731581772;10655.360678359324;10890.727731806768;53983.46016539942;54037.72981135069;54809.1778623313;56130.43869561252;56639;84814.57592711996;84886;85026.57486712527;85920.14079859121;88594.11405885858;13275.734485310046;13567.253801041037;13594;13639.249841258428;14473.85526144725;15514.22428878529;15516.37934482608;16249.918750406172;17486.650266994333;17647.558811029827;13747.900167986738;14812.185329806533;14844.257787110386;15127.54617361481;15447.459338923083;15494.225288735744;15647.139407332706;15791.21043947775;15898.761518577;16929.91535042317;14640.341184646504;15256.694866102394;15729.606759831096;15818.60453488672;16082.59793505125;11997.40012999364;12129.150959433107;12236.632901012983;12273.938630306791;12847.807282890952;12502.437390317276;12535.561255356019;12579;12792.042448217826;12849.614511564669 16:43:40 INFO - PROCESS | 5822 | __end_tp_report 16:43:40 INFO - PROCESS | 5822 | __start_cc_report 16:43:40 INFO - PROCESS | 5822 | _x_x_mozilla_cycle_collect,1007 16:43:40 INFO - PROCESS | 5822 | __end_cc_report 16:43:40 INFO - PROCESS | 5822 | __startTimestamp1477352620069__endTimestamp 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | ------- Summary: start ------- 16:43:40 INFO - PROCESS | 5822 | Number of tests: 6 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#0] dojo.html Cycles:165 Average:5563.47 Median:5759.63 stddev:626.32 (10.9%) stddev-sans-first:600.83 16:43:40 INFO - PROCESS | 5822 | Values: 3227.2 3238.6 3262.5 3272.9 3304.9 5544.6 5631.5 5634.9 5640.0 5644.4 5623.2 5666.9 5676.8 5678.9 5688.7 5134.5 5146.8 5152.5 5215.2 5218.0 4933.5 5005.0 5184.7 5233.7 5257.8 4999.1 5041.6 5067.5 5077.6 5085.6 5086.6 5129.0 5187.8 5189.8 5219.2 4868.9 5080.7 5101.2 5138.1 5160.7 5343.8 5436.2 5458.1 5465.3 5481.9 5866.1 5911.8 5962.1 5967.9 5968.8 5785.0 5859.6 6004.8 6013.3 6015.8 5511.2 5545.3 5575.4 5580.0 5616.1 6067.5 6083.0 6091.8 6094.3 6096.7 5678.7 5781.9 5868.1 5947.9 5961.4 6008.2 6017.8 6036.8 6038.9 6056.3 5938.7 5948.8 5967.0 5970.6 5979.9 5717.9 5900.9 5949.7 5960.2 5964.9 4483.2 4527.2 4586.6 4605.8 4626.1 4074.2 4093.3 4102.7 4118.8 4140.9 5498.8 5722.6 5739.9 5752.8 5753.1 5919.7 5999.7 6015.7 6039.4 6063.8 5750.2 5752.7 5754.9 5772.0 5780.0 4966.1 5234.9 5236.9 5238.0 5240.0 6126.8 6207.2 6215.3 6294.2 6299.9 6001.1 6018.7 6020.6 6021.2 6061.1 5963.6 6216.5 6263.1 6297.8 6306.5 5808.0 5901.4 5913.2 5954.5 5962.7 6022.5 6027.0 6029.2 6037.6 6052.5 5538.2 5887.8 5896.9 5897.1 5900.5 5755.5 5832.1 5835.2 5895.5 5916.6 5854.7 5855.1 5863.9 5864.6 5868.4 5631.4 5816.0 5838.9 5884.9 5913.7 5588.5 5694.9 5699.3 5723.1 5759.6 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#1] ext.html Cycles:180 Average:67185.89 Median:40735.30 stddev:137899.47 (338.5%) stddev-sans-first:138195.68 16:43:40 INFO - PROCESS | 5822 | Values: 592.9 634.2 659.5 662.6 674.7 34116.0 45763.0 47230.3 47340.5 47392.3 47801.0 48061.5 48227.8 48298.3 48338.8 8743.6 9360.6 9497.4 9514.4 9516.6 11043.4 11289.0 11313.4 11700.5 11701.2 11405.2 11520.2 11522.9 11526.0 11529.4 11357.8 11420.3 11424.5 11431.7 11434.2 11150.8 11236.1 11258.3 11262.5 11267.6 44279.8 44931.3 45054.3 45085.0 45091.5 3837.8 3866.6 3970.2 3975.1 4053.8 34369.7 36510.6 36576.3 36658.8 36872.8 4792.8 4816.9 4888.3 4902.1 4947.8 413746.0 415087.0 415398.0 416567.0 418920.0 70043.0 70844.0 70924.0 71002.0 71028.6 39737.6 40711.0 40759.6 42972.1 42979.6 19296.8 19590.1 19609.6 19610.2 19614.7 13699.0 14550.7 14853.8 14967.9 15084.5 21459.8 22156.3 22190.3 22209.7 22260.3 7933.2 8096.4 8099.0 8204.8 8338.7 956.2 998.0 1024.4 1129.6 1179.6 33772.3 34123.8 34343.7 34379.8 34666.5 1200.3 1204.4 1243.9 1254.6 1298.4 17967.0 18001.5 18119.6 18120.2 18183.0 670324.0 672985.0 675683.0 676303.0 682337.0 63152.0 63803.4 63915.0 63951.0 63953.4 1312.2 1406.2 1418.1 1424.4 1465.5 33938.7 36225.6 36240.8 36343.0 36375.6 53144.0 53804.5 53820.0 53873.5 53885.7 417788.0 418926.0 419798.0 419810.0 421659.0 26074.5 27698.0 27768.7 29730.4 29745.7 51058.0 52008.0 52120.0 52164.9 52198.5 43521.8 44797.0 44872.8 45374.8 45427.8 44444.6 44981.8 45406.3 45471.0 45576.5 43516.3 44297.6 44506.8 44544.3 44671.6 37454.3 39366.8 39570.0 40068.2 40691.4 29668.3 29730.9 30092.8 30163.4 30195.8 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#2] jquery.html Cycles:180 Average:31326.34 Median:12215.31 stddev:90314.04 (739.4%) stddev-sans-first:90537.51 16:43:40 INFO - PROCESS | 5822 | Values: 385.6 386.6 399.8 400.6 420.6 13913.4 19128.6 20884.1 20918.5 22242.7 19127.1 19999.7 20034.9 20321.8 20424.2 5570.5 5695.0 5706.7 5707.6 5709.3 4202.6 5649.0 5688.1 5688.8 5689.4 6565.8 6768.3 6862.4 6917.3 6929.1 6251.7 6310.9 6316.3 6687.6 6898.0 6010.4 6021.6 6154.4 6154.9 6161.9 14749.9 15187.4 15200.7 15201.2 17785.0 2340.7 2552.2 2930.0 3144.3 3222.6 10784.7 11262.0 14271.0 14416.8 14806.2 1915.9 1971.9 2025.2 2033.0 2057.7 60698.3 65990.0 73138.6 73533.3 80332.6 11126.6 11371.9 11591.9 11773.2 12213.0 16794.7 17047.1 17078.9 17081.5 17092.1 8617.2 8899.6 9010.7 9120.2 9250.9 8958.9 9106.5 9117.3 9241.8 9247.9 4936.2 5075.7 5101.6 5174.7 5231.5 2076.4 2077.6 2115.1 2153.1 2187.9 469.6 483.8 928.8 1099.3 1100.9 12478.7 12736.0 12897.7 12915.5 12985.4 509.1 520.2 526.3 535.2 548.9 6912.3 6999.8 7104.6 7115.4 7184.0 537539.0 547673.0 548732.0 554212.0 554550.0 93625.6 94627.1 94808.4 116399.0 118195.4 1064.2 1074.8 1109.5 1110.7 1112.5 18668.7 19061.3 19181.9 19186.0 19612.2 35652.5 45349.0 45924.8 46415.3 47115.0 64862.4 68894.7 69206.3 69340.0 69512.0 9155.0 12249.8 13535.3 13742.3 14054.7 14415.0 14616.3 14932.3 15350.8 15382.7 13342.1 13360.7 13366.7 13582.1 13915.8 12663.6 13266.7 13298.2 13530.0 13600.7 13173.3 13297.1 13657.9 13831.7 13896.4 13281.3 13330.6 13404.9 13461.2 13466.9 6110.9 13353.0 13407.6 13524.9 13588.6 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#3] mootools.html Cycles:180 Average:6403.41 Median:2670.20 stddev:3773.04 (141.3%) stddev-sans-first:3764.57 16:43:40 INFO - PROCESS | 5822 | Values: 1357.9 1462.4 1499.5 1642.8 1848.7 8545.9 9411.9 9745.9 9752.9 9998.2 9762.9 9856.3 9862.3 9870.2 9871.7 2532.7 2560.6 2566.0 2581.7 2585.1 2478.8 2520.6 2538.7 2541.3 2542.8 2471.5 2486.6 2533.2 2535.6 2537.1 2413.3 2423.6 2432.9 2475.8 2476.6 2360.9 2395.1 2404.9 2412.4 2459.0 9559.3 9754.2 9820.0 9821.3 9831.4 5805.6 5996.2 6026.0 6026.4 6045.3 3596.5 3649.7 3657.4 3666.5 3701.6 1216.3 1229.1 1273.8 1303.7 1320.0 14176.0 14332.9 14348.3 14354.0 14379.1 4855.8 4897.1 4981.9 5073.9 5075.9 10866.7 10871.6 10873.7 10873.9 10881.1 5715.2 5748.4 5749.7 5757.4 5760.1 4738.6 5150.5 5252.8 5273.4 5327.7 4132.6 4148.0 4236.6 4247.8 4267.4 1236.7 1243.7 1243.8 1271.0 1284.6 931.0 990.5 1001.3 1005.2 1033.9 6396.3 6489.7 6681.3 6694.4 6856.5 476.8 484.6 488.1 504.2 513.9 4402.5 4485.4 4487.8 4585.5 4585.7 12079.5 12612.2 12615.3 12622.2 12622.8 11542.3 12148.5 12149.1 12511.2 12512.4 8688.2 8831.2 8880.0 8881.4 8887.0 6415.6 6481.2 6616.0 6642.5 6689.1 11312.9 11391.5 11442.7 11695.5 11882.6 11447.3 11678.8 11890.9 11914.9 12063.9 7804.8 8009.8 8020.4 8546.1 8770.0 8745.7 9267.6 9519.4 9590.5 9860.6 8418.0 8560.5 8592.7 8618.6 8692.6 8545.5 8555.6 8573.2 8659.3 8681.8 7530.8 7557.9 7597.2 7603.4 7604.8 5585.2 5675.2 5696.3 5764.4 5846.0 5732.2 5775.9 5872.9 5887.9 5923.6 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#4] prototype.html Cycles:180 Average:4703.52 Median:5183.94 stddev:1322.76 (25.5%) stddev-sans-first:1285.61 16:43:40 INFO - PROCESS | 5822 | Values: 356.8 365.2 365.8 366.5 369.3 5513.1 5515.6 5523.7 5527.3 5584.3 5274.5 5429.6 5509.8 5510.7 5538.8 4342.7 4357.4 4363.5 4369.8 4384.9 4346.2 4353.4 4376.5 4381.6 4422.3 4318.2 4336.2 4336.7 4390.0 4390.5 4291.7 4325.0 4327.5 4327.7 4345.2 3901.2 3904.3 3904.7 3913.3 3915.6 5278.7 5304.4 5344.4 5378.6 5384.2 4847.2 4937.7 4986.4 5013.5 5032.5 5626.0 5672.5 5680.2 5724.0 5772.2 4296.3 4310.7 4312.0 4317.3 4322.3 5583.3 5703.9 5887.6 5889.1 5898.5 4328.8 4341.8 4343.0 4346.2 4366.5 4388.3 4412.5 4425.9 4429.0 4446.9 4849.4 4922.5 4952.3 4958.6 4986.2 5632.8 5685.4 5692.7 5705.6 5712.2 1522.3 1538.5 1540.3 1545.5 1548.0 964.6 995.9 998.8 1002.6 1029.3 4911.5 4924.5 4977.9 5043.7 5062.7 5186.5 5208.7 5228.5 5230.8 5236.5 4820.0 4850.4 4880.3 5008.5 5031.8 2716.9 2728.9 2731.7 2748.7 2753.9 5360.7 5385.8 5414.8 5437.8 5458.3 5029.5 5191.6 5230.8 5245.8 5313.2 5323.8 5331.9 5339.3 5375.0 5386.0 5141.0 5161.8 5181.4 5220.7 5223.3 4814.5 5070.5 5082.3 5091.3 5096.0 5367.3 5896.7 5967.9 6080.0 6102.8 4339.7 4361.7 4390.2 4398.4 4418.6 5622.6 5888.3 5925.0 5929.9 5937.2 5702.2 5713.3 5716.3 5735.4 5738.8 5661.8 5678.7 5713.7 5718.5 5722.5 5479.4 5665.8 5700.1 5705.6 5705.7 5722.5 5740.6 5751.9 5755.6 5778.8 5613.9 5615.8 5632.8 5672.9 5683.4 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - PROCESS | 5822 | [#5] yui.html Cycles:180 Average:23020.77 Median:4078.74 stddev:33461.62 (820.4%) stddev-sans-first:33513.85 16:43:40 INFO - PROCESS | 5822 | Values: 790.0 804.0 815.3 839.7 866.7 15980.0 16571.8 16574.7 16581.9 16588.9 15335.0 15623.1 15624.0 15647.5 15664.8 699.2 826.4 834.1 850.6 856.1 842.1 842.7 844.4 844.4 845.2 900.4 901.3 901.5 901.7 902.1 901.2 902.0 903.1 903.4 903.5 660.0 660.4 662.4 662.7 662.8 15145.9 15161.2 15168.8 15208.0 15210.5 15994.6 16444.4 16620.2 16899.7 17182.0 13003.3 13014.5 13093.3 13444.0 13647.7 4087.5 4100.9 4135.6 4183.6 4212.5 72179.6 72951.3 89517.0 91042.5 93078.0 11599.1 12218.6 12223.1 12572.6 12596.4 20270.9 22820.0 22848.7 22852.4 22888.0 8544.6 9021.1 9167.8 9194.3 9214.5 4976.4 5117.9 5191.9 5299.2 5447.8 4070.0 4199.7 4215.9 4224.2 4239.5 702.9 705.5 709.0 709.3 734.0 641.7 689.2 817.5 835.0 840.9 9995.4 10706.3 10901.5 11396.8 11468.4 731.0 747.3 758.4 798.8 834.1 4795.2 4857.5 4966.4 5075.1 5188.8 122301.0 123474.0 126006.0 134607.0 134825.3 107382.5 108766.5 109699.0 109869.0 111871.4 89954.6 96035.0 99101.0 100050.0 100394.0 9998.9 10240.5 10530.7 10655.4 10890.7 53983.5 54037.7 54809.2 56130.4 56639.0 84814.6 84886.0 85026.6 85920.1 88594.1 13275.7 13567.3 13594.0 13639.2 14473.9 15514.2 15516.4 16249.9 17486.7 17647.6 13747.9 14812.2 14844.3 15127.5 15447.5 15494.2 15647.1 15791.2 15898.8 16929.9 14640.3 15256.7 15729.6 15818.6 16082.6 11997.4 12129.2 12236.6 12273.9 12847.8 12502.4 12535.6 12579.0 12792.0 12849.6 16:43:40 INFO - PROCESS | 5822 | -------- Summary: end -------- 16:43:40 INFO - PROCESS | 5822 | 16:43:40 INFO - TEST-INFO | 5822: exit 0 16:43:40 INFO - TEST-OK | dromaeo_css | took 1112740ms 16:43:40 INFO - TEST-START | kraken 16:43:40 INFO - operating with platform_type : linux_ 16:43:40 INFO - Initialising browser for kraken test... 16:43:40 INFO - TEST-INFO | started process 6013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeOCNTC/profile http://localhost:42581/getInfo.html) 16:43:44 INFO - PROCESS | 6013 | __metrics Screen width/height:1600/1200 16:43:44 INFO - PROCESS | 6013 | colorDepth:24 16:43:44 INFO - PROCESS | 6013 | Browser inner width/height: 1024/697 16:43:44 INFO - PROCESS | 6013 | __metrics 16:43:45 INFO - PROCESS | 6013 | 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" 16:43:45 INFO - PROCESS | 6013 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:43:45 INFO - PROCESS | 6013 | console.error: PushService: 16:43:45 INFO - PROCESS | 6013 | stateChangeProcessEnqueue: Error transitioning state 16:43:45 INFO - PROCESS | 6013 | UnknownError 16:43:46 INFO - TEST-INFO | 6013: exit 0 16:43:46 INFO - Browser initialized. 16:43:46 INFO - Running cycle 1/1 for kraken test... 16:43:46 INFO - TEST-INFO | started process 6102 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeOCNTC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:57 INFO - PROCESS | 6102 | RSS: Main: 144547840 16:43:57 INFO - PROCESS | 6102 | 16:44:00 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42581/tests/kraken/driver.html?testName=audio-beat-detection) 16:44:01 INFO - PROCESS | 6102 | RSS: Main: 199114752 16:44:01 INFO - PROCESS | 6102 | 16:44:03 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42581/tests/kraken/driver.html?testName=audio-dft) 16:44:04 INFO - PROCESS | 6102 | RSS: Main: 184078336 16:44:04 INFO - PROCESS | 6102 | 16:44:07 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42581/tests/kraken/driver.html?testName=audio-fft) 16:44:07 INFO - PROCESS | 6102 | RSS: Main: 275275776 16:44:07 INFO - PROCESS | 6102 | 16:44:09 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42581/tests/kraken/driver.html?testName=audio-oscillator) 16:44:10 INFO - PROCESS | 6102 | RSS: Main: 180449280 16:44:10 INFO - PROCESS | 6102 | 16:44:12 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42581/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:44:13 INFO - PROCESS | 6102 | RSS: Main: 179351552 16:44:13 INFO - PROCESS | 6102 | 16:44:16 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42581/tests/kraken/driver.html?testName=imaging-darkroom) 16:44:16 INFO - PROCESS | 6102 | RSS: Main: 215318528 16:44:16 INFO - PROCESS | 6102 | 16:44:20 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42581/tests/kraken/driver.html?testName=imaging-desaturate) 16:44:20 INFO - PROCESS | 6102 | RSS: Main: 214405120 16:44:20 INFO - PROCESS | 6102 | 16:44:23 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42581/tests/kraken/driver.html?testName=json-parse-financial) 16:44:24 INFO - PROCESS | 6102 | RSS: Main: 213901312 16:44:24 INFO - PROCESS | 6102 | 16:44:25 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42581/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:44:26 INFO - PROCESS | 6102 | RSS: Main: 175742976 16:44:26 INFO - PROCESS | 6102 | 16:44:27 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:44:28 INFO - PROCESS | 6102 | RSS: Main: 183824384 16:44:28 INFO - PROCESS | 6102 | 16:44:30 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:44:30 INFO - PROCESS | 6102 | RSS: Main: 188968960 16:44:30 INFO - PROCESS | 6102 | 16:44:33 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:44:34 INFO - PROCESS | 6102 | RSS: Main: 192864256 16:44:34 INFO - PROCESS | 6102 | 16:44:36 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:44:37 INFO - PROCESS | 6102 | RSS: Main: 193785856 16:44:37 INFO - PROCESS | 6102 | 16:44:38 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:42581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42581/tests/kraken/driver.html?testName=ai-astar) 16:44:39 INFO - PROCESS | 6102 | RSS: Main: 244961280 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | __start_tp_report 16:44:39 INFO - PROCESS | 6102 | _x_x_mozilla_page_load 16:44:39 INFO - PROCESS | 6102 | _x_x_mozilla_page_load_details 16:44:39 INFO - PROCESS | 6102 | |i|pagename|runs| 16:44:39 INFO - PROCESS | 6102 | |0;ai-astar;112;111;118;109;110;112;111;109;109;110 16:44:39 INFO - PROCESS | 6102 | |1;audio-beat-detection;148;116;145;172;114;149;142;117;176;115 16:44:39 INFO - PROCESS | 6102 | |2;audio-dft;160;161;162;160;163;159;161;161;160;160 16:44:39 INFO - PROCESS | 6102 | |3;audio-fft;75;75;74;166;76;75;75;76;171;74 16:44:39 INFO - PROCESS | 6102 | |4;audio-oscillator;82;83;154;84;83;84;143;82;83;147 16:44:39 INFO - PROCESS | 6102 | |5;imaging-gaussian-blur;116;116;116;118;117;117;117;117;116;117 16:44:39 INFO - PROCESS | 6102 | |6;imaging-darkroom;145;147;145;145;146;145;145;146;145;145 16:44:39 INFO - PROCESS | 6102 | |7;imaging-desaturate;95;96;95;96;95;94;94;95;95;94 16:44:39 INFO - PROCESS | 6102 | |8;json-parse-financial;49;49;50;50;51;58;50;49;48;49 16:44:39 INFO - PROCESS | 6102 | |9;json-stringify-tinderbox;58;60;59;59;59;58;58;58;60;61 16:44:39 INFO - PROCESS | 6102 | |10;stanford-crypto-aes;72;72;72;73;79;74;74;72;88;74 16:44:39 INFO - PROCESS | 6102 | |11;stanford-crypto-ccm;119;121;123;123;118;117;116;119;178;138 16:44:39 INFO - PROCESS | 6102 | |12;stanford-crypto-pbkdf2;155;152;149;149;154;151;149;147;151;151 16:44:39 INFO - PROCESS | 6102 | |13;stanford-crypto-sha256-iterative;62;66;62;61;62;63;60;60;62;60 16:44:39 INFO - PROCESS | 6102 | __end_tp_report 16:44:39 INFO - PROCESS | 6102 | __start_cc_report 16:44:39 INFO - PROCESS | 6102 | _x_x_mozilla_cycle_collect,1625 16:44:39 INFO - PROCESS | 6102 | __end_cc_report 16:44:39 INFO - PROCESS | 6102 | __startTimestamp1477352679178__endTimestamp 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | ------- Summary: start ------- 16:44:39 INFO - PROCESS | 6102 | Number of tests: 14 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:2.69 (2.4%) stddev-sans-first:2.83 16:44:39 INFO - PROCESS | 6102 | Values: 112.0 111.0 118.0 109.0 110.0 112.0 111.0 109.0 109.0 110.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:146.50 stddev:23.32 (15.9%) stddev-sans-first:24.53 16:44:39 INFO - PROCESS | 6102 | Values: 148.0 116.0 145.0 172.0 114.0 149.0 142.0 117.0 176.0 115.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 16:44:39 INFO - PROCESS | 6102 | Values: 160.0 161.0 162.0 160.0 163.0 159.0 161.0 161.0 160.0 160.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#3] audio-fft Cycles:10 Average:93.70 Median:75.00 stddev:39.45 (52.6%) stddev-sans-first:41.25 16:44:39 INFO - PROCESS | 6102 | Values: 75.0 75.0 74.0 166.0 76.0 75.0 75.0 76.0 171.0 74.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.00 stddev:31.51 (38.0%) stddev-sans-first:32.54 16:44:39 INFO - PROCESS | 6102 | Values: 82.0 83.0 154.0 84.0 83.0 84.0 143.0 82.0 83.0 147.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:44:39 INFO - PROCESS | 6102 | Values: 116.0 116.0 116.0 118.0 117.0 117.0 117.0 117.0 116.0 117.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 16:44:39 INFO - PROCESS | 6102 | Values: 145.0 147.0 145.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 16:44:39 INFO - PROCESS | 6102 | Values: 95.0 96.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 94.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:2.83 (5.7%) stddev-sans-first:2.96 16:44:39 INFO - PROCESS | 6102 | Values: 49.0 49.0 50.0 50.0 51.0 58.0 50.0 49.0 48.0 49.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:1.05 (1.8%) stddev-sans-first:1.05 16:44:39 INFO - PROCESS | 6102 | Values: 58.0 60.0 59.0 59.0 59.0 58.0 58.0 58.0 60.0 61.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:74.00 stddev:5.03 (6.8%) stddev-sans-first:5.22 16:44:39 INFO - PROCESS | 6102 | Values: 72.0 72.0 72.0 73.0 79.0 74.0 74.0 72.0 88.0 74.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:122.00 stddev:18.91 (15.5%) stddev-sans-first:19.83 16:44:39 INFO - PROCESS | 6102 | Values: 119.0 121.0 123.0 123.0 118.0 117.0 116.0 119.0 178.0 138.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.80 Median:151.00 stddev:2.44 (1.6%) stddev-sans-first:2.06 16:44:39 INFO - PROCESS | 6102 | Values: 155.0 152.0 149.0 149.0 154.0 151.0 149.0 147.0 151.0 151.0 16:44:39 INFO - PROCESS | 6102 | 16:44:39 INFO - PROCESS | 6102 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.81 (2.9%) stddev-sans-first:1.92 16:44:39 INFO - PROCESS | 6102 | Values: 62.0 66.0 62.0 61.0 62.0 63.0 60.0 60.0 62.0 60.0 16:44:39 INFO - PROCESS | 6102 | -------- Summary: end -------- 16:44:39 INFO - PROCESS | 6102 | 16:44:40 INFO - TEST-INFO | 6102: exit 0 16:44:40 INFO - TEST-OK | kraken | took 59377ms 16:44:40 INFO - SUITE-END | took 1172s 16:44:40 INFO - Completed test suite (00:19:32) 16:44:40 INFO - javascript benchmark 16:44:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.20933371324, 3238.643749187589, 3262.543243945848, 3272.8527216275265, 3304.9339013219737, 5544.639598426104, 5631.549476041917, 5634.915476267859, 5640.012997725397, 5644.435556444359, 5623.212750214963, 5666.858328541778, 5676.772929082842, 5678.943210567893, 5688.658680479174, 5134.460881607433, 5146.794128234867, 5152.458991805863, 5215.243789650517, 5218.034663587248, 4933.481984391641, 5004.974975125119, 5184.688918664892, 5233.685978841263, 5257.842264732064, 4999.075171093341, 5041.57146642534, 5067.543921047105, 5077.5684066854365, 5085.593152547787, 5086.593072554206, 5128.974355128238, 5187.766550505236, 5189.818356357547, 5219.19102539107, 4868.902621947576, 5080.745962701887, 5101.20931255656, 5138.10083235436, 5160.7419629018395, 5343.8396848094235, 5436.184572314145, 5458.0721277382545, 5465.316835395575, 5481.890362192734, 5866.061430171195, 5911.822645320647, 5962.105684147369, 5967.850803729898, 5968.7612495500125, 5785.045467497858, 5859.589828711992, 6004.8498787530225, 6013.338532761392, 6015.849603759941, 5511.22842802008, 5545.306836645419, 5575.414581468887, 5579.972100139514, 5616.129499927477, 6067.514598832171, 6083.0, 6091.817245482643, 6094.329623741385, 6096.664683442454, 5678.744456499468, 5781.855453613609, 5868.09044598088, 5947.940520594739, 5961.433663801932, 6008.158857759917, 6017.788870272515, 6036.849078773066, 6038.909416358758, 6056.273247210363, 5938.703064846741, 5948.791792287249, 5966.970165149233, 5970.582059255898, 5979.850503737442, 5717.885642287131, 5900.940990590125, 5949.672767997715, 5960.225170727777, 5964.850878728066, 4483.21543729846, 4527.207738645724, 4586.610138138229, 4605.815767369335, 4626.074785043005, 4074.1647962167685, 4093.263212621757, 4102.712810103324, 4118.835246590163, 4140.944059264868, 5498.80754173602, 5722.599418040781, 5739.942600574024, 5752.798652047159, 5753.079507278899, 5919.733611987471, 5999.730012149464, 6015.699215039231, 6039.396060394013, 6063.7574497020505, 5750.1949727038245, 5752.654840709571, 5754.942450575524, 5771.9711401443565, 5780.046292361797, 4966.1061009018, 5234.869128271747, 5236.895262094737, 5238.0, 5240.0, 6126.8230438726505, 6207.162033125561, 6215.34738852423, 6294.244690637152, 6299.874002520016, 6001.06983417571, 6018.699065046642, 6020.608660437058, 6021.217241758543, 6061.121137434959, 5963.642181469126, 6216.502679785516, 6263.060540918898, 6297.842553936188, 6306.527010474325, 5808.01263785166, 5901.439363260483, 5913.172155898264, 5954.46409823118, 5962.701864906824, 6022.45797878193, 6027.0, 6029.155918171372, 6037.607555508878, 6052.485538729257, 5538.224648549126, 5887.764489420547, 5896.941030589639, 5897.144913987534, 5900.498457631149, 5755.53955683536, 5832.066869300891, 5835.2122463466885, 5895.528357731457, 5916.556258280732, 5854.736536855767, 5855.121731740102, 5863.912041319469, 5864.560157988082, 5868.38381969887, 5631.4368563145, 5816.0, 5838.883222335615, 5884.852878678067, 5913.674747888907, 5588.524975377139, 5694.943050569441, 5699.344575373885, 5723.084306510938, 5759.625624334572], "value": 5521.806896586105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.8962431574473, 634.2167423232307, 659.5350278053972, 662.6487961380464, 674.7132468700801, 34116.0, 45763.0, 47230.291545626846, 47340.526594734045, 47392.28911566329, 47801.0, 48061.51938480614, 48227.75886120573, 48298.27552586714, 48338.75830620851, 8743.562821858914, 9360.57877395517, 9497.430154190735, 9514.381565198275, 9516.571754271039, 11043.392613406259, 11289.040431563328, 11313.377764222965, 11700.473478693437, 11701.239419437708, 11405.20163588549, 11520.19358644891, 11522.884771152265, 11526.020288275507, 11529.365884876328, 11357.829632555518, 11420.257683250562, 11424.543018279343, 11431.714207144805, 11434.199606027581, 11150.776984460346, 11236.101111911028, 11258.324500529994, 11262.493187806569, 11267.60563380286, 44279.77860110711, 44931.32603010957, 45054.32418513725, 45085.0, 45091.54908450906, 3837.846486140551, 3866.5746767855794, 3970.1861118470642, 3975.1056012397266, 4053.783864840536, 34369.656303436896, 36510.63489365099, 36576.268474630626, 36658.8167059163, 36872.81563592165, 4792.7843247053615, 4816.8554943351755, 4888.266759985995, 4902.093112774112, 4947.826826061107, 413746.0, 415087.0, 415398.0, 416567.0, 418920.0, 70043.0, 70844.0, 70924.0, 71002.0, 71028.64485677538, 39737.602623973966, 40711.0, 40759.592404076175, 42972.140557189305, 42979.57020429818, 19296.80703192978, 19590.118444670024, 19609.607807844048, 19610.215591376185, 19614.70577941332, 13699.0, 14550.708985820436, 14853.777193342108, 14967.925160374129, 15084.547463576111, 21459.78540214609, 22156.33530994073, 22190.334289971328, 22209.666854997187, 22260.332190034325, 7933.206679332136, 8096.433249672584, 8098.959505202436, 8204.83590328202, 8338.666453341919, 956.2156499635078, 997.9900200997897, 1024.3827021958818, 1129.6215767717742, 1179.6225207933378, 33772.32455350879, 34123.829380852934, 34343.656563434546, 34379.77718938932, 34666.48000279998, 1200.3218181727295, 1204.3917821500086, 1243.875612438749, 1254.5609036837216, 1298.4351806964, 17967.0, 18001.459956201335, 18119.63760724778, 18120.184591693407, 18183.0, 670324.0, 672985.0, 675683.0, 676303.0, 682337.0, 63152.0, 63803.36196638067, 63915.0, 63951.04073438902, 63953.36046639567, 1312.173330801608, 1406.184413040434, 1418.14202407544, 1424.3732757586629, 1465.545680838922, 33938.66061339454, 36225.637743622225, 36240.81879590585, 36343.0, 36375.63624363722, 53144.0, 53804.461955381514, 53820.0, 53873.461265386846, 53885.730571346896, 417788.0, 418926.0, 419798.0, 419810.0, 421659.0, 26074.49431927678, 27698.0, 27768.722312776612, 29730.405391891607, 29745.70254297516, 51058.0, 52008.0, 52120.0, 52164.87010779366, 52198.478015220884, 43521.78239108784, 44797.0, 44872.77563612161, 45374.77312613548, 45427.77286113548, 44444.55555444534, 44981.775091124335, 45406.31890521579, 45471.0, 45576.45013041858, 43516.347254791835, 44297.55702442934, 44506.777466112464, 44544.33183502315, 44671.55328446804, 37454.25091498209, 39366.80316598398, 39570.0, 40068.1986360277, 40691.389629156176, 29668.258293542833, 29730.851345743133, 30092.849535752182, 30163.396732064797, 30195.849020754755], "value": 22125.540559276287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.57394079542087, 386.551600143833, 399.8180827723385, 400.56538655558717, 420.5605142625956, 13913.443462261497, 19128.617427651458, 20884.06021729022, 20918.477038074056, 22242.666360004612, 19127.139278732455, 19999.700004499944, 20034.89982550089, 20321.796782032176, 20424.183032678677, 5570.470805273502, 5695.03184458643, 5706.714664266791, 5707.571932105088, 5709.343425506078, 4202.642775364083, 5649.0, 5688.11834165705, 5688.829335119933, 5689.402612725667, 6565.835854103638, 6768.289329620393, 6862.416694580967, 6917.308269173092, 6929.09921710177, 6251.718672659718, 6310.905336419957, 6316.336784637593, 6687.5653082549725, 6897.965510172442, 6010.429009244115, 6021.638701677914, 6154.415330543591, 6154.907676384858, 6161.876762464771, 14749.852501474956, 15187.392504299814, 15200.695986080327, 15201.163935983517, 17785.0, 2340.66060421238, 2552.1577879299784, 2929.9707002929913, 3144.3239703463637, 3222.5810644616286, 10784.739160144994, 11261.98642122213, 14271.0, 14416.774574161103, 14806.185659788707, 1915.9425217243504, 1971.8619696621242, 2025.1899240303849, 2032.9898350508154, 2057.68105943579, 60698.304344806216, 65990.0, 73138.63430682865, 73533.26466735317, 80332.59833700911, 11126.554937802397, 11371.862813718562, 11591.942040289914, 11773.17587768848, 12213.0, 16794.74039447022, 17047.062411567484, 17078.914605426893, 17081.48755537336, 17092.145392730316, 8617.224449799549, 8899.555022248862, 9010.684626038057, 9120.179183873483, 9250.86123708145, 8958.86561701575, 9106.49914254709, 9117.270618350649, 9241.768955776159, 9247.861282080774, 4936.185529387611, 5075.720835354017, 5101.591872650179, 5174.722517035826, 5231.476852314814, 2076.356329537818, 2077.5588683297065, 2115.0905110802196, 2153.12798316682, 2187.9453013674784, 469.5984932882405, 483.7508683028243, 928.8281667891462, 1099.3239157917983, 1100.9009189173014, 12478.689737577604, 12736.0, 12897.742045159044, 12915.483380664857, 12985.35073246334, 509.1319300592481, 520.217073304674, 526.3157894736827, 535.2159086937655, 548.8627843039241, 6912.343327383891, 6999.790006299819, 7104.6447677615915, 7115.359617634438, 7183.964080179566, 537539.0, 547673.0, 548732.0, 554212.0, 554550.0, 93625.58711697553, 94627.05372946183, 94808.43865061118, 116399.0, 118195.40902295434, 1064.164630764846, 1074.8065348237235, 1109.5229051507768, 1110.6612483192703, 1112.5327362507605, 18668.7199692002, 19061.332853350064, 19181.904090479456, 19186.0, 19612.215511379385, 35652.46521302131, 45349.0, 45924.770376147906, 46415.303770444145, 47115.0, 64862.35137648752, 68894.65552672405, 69206.307936922, 69340.0, 69512.0, 9154.954225228832, 12249.816252756395, 13535.25556094385, 13742.312884355542, 14054.718905621627, 14415.0, 14616.342264598332, 14932.328045237773, 15350.846491534941, 15382.692346153239, 13342.13276137048, 13360.665983350495, 13366.660180902825, 13582.117162384413, 13915.791263131263, 12663.556775512812, 13266.673332666656, 13298.202107873558, 13530.0, 13600.727985440039, 13173.341332933123, 13297.135686180367, 13657.931710341385, 13831.654208644864, 13896.444142234197, 13281.269530175932, 13330.600081997556, 13404.865951340362, 13461.192328460324, 13466.865331346562, 6110.877782444415, 13353.0, 13407.597772066854, 13524.864751352361, 13588.59234222975], "value": 9549.061179914801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.9388927498262, 1462.3660204392083, 1499.4976682811257, 1642.8275031121732, 1848.713449415341, 8545.871811922818, 9411.858822117674, 9745.902540974588, 9752.902470975288, 9998.200143988483, 9762.853557196648, 9856.310058295905, 9862.30963832532, 9870.161036311903, 9871.703848884526, 2532.734062923394, 2560.5519034169038, 2566.0, 2581.6901971763414, 2585.146901522493, 2478.8388754730886, 2520.5841036229, 2538.6826646669165, 2541.326548464649, 2542.7965762739027, 2471.45627961849, 2486.589712697403, 2533.1513942829174, 2535.632333311669, 2537.0612873236837, 2413.336332508565, 2423.5879900416885, 2432.927012189637, 2475.8266921315517, 2476.628505724138, 2360.893759780814, 2395.101836811196, 2404.927852164438, 2412.3848418653306, 2459.02868366994, 9559.283053770994, 9754.170895473822, 9819.950900245452, 9821.263405244636, 9831.360961537477, 5805.564582656318, 5996.220491336098, 6026.0, 6026.397360263983, 6045.274567051938, 3596.496490491333, 3649.7080233581514, 3657.414813629833, 3666.4866918631415, 3701.6298370163036, 1216.318861437589, 1229.1088960503564, 1273.7898246789268, 1303.6805982534245, 1320.0, 14176.0, 14332.928335358256, 14348.282585870666, 14354.0, 14379.065360751518, 4855.757212139379, 4897.118518666674, 4981.90036199274, 5073.923891141636, 5075.949240507622, 10866.72833179161, 10871.619493317854, 10873.728156795985, 10873.891261087447, 10881.075108615856, 5715.199872017921, 5748.367679555246, 5749.712514374264, 5757.395473475309, 5760.107183386582, 4738.597219236334, 5150.48495150482, 5252.842414727565, 5273.3935597406025, 5327.680339179662, 4132.621383335403, 4148.0, 4236.555161708038, 4247.766372849461, 4267.4025636410925, 1236.7031912341154, 1243.7325974915436, 1243.7636848998661, 1270.9618711438673, 1284.6274580371587, 930.9805762689844, 990.4552496127101, 1001.3140998416109, 1005.2410430125209, 1033.8707661542308, 6396.2964073951825, 6489.675516224264, 6681.33186681328, 6694.397504224643, 6856.5200435969, 476.81880885263377, 484.64862974343316, 488.060483569128, 504.1681225977094, 513.8689634143269, 4402.493713222956, 4485.394471746337, 4487.820487180542, 4585.495595484494, 4585.72485650862, 12079.516819327304, 12612.180208286432, 12615.30615816139, 12622.179558328678, 12622.81065784014, 11542.307461552165, 12148.45331960064, 12149.08881833866, 12511.249325040526, 12512.37438128108, 8688.174623410765, 8831.161039701217, 8879.955600222087, 8881.42270752399, 8887.022427532966, 6415.582987105823, 6481.222253329631, 6616.040674102324, 6642.501812363996, 6689.063531105552, 11312.94343528277, 11391.544338226378, 11442.656720298404, 11695.532178712756, 11882.643520694393, 11447.313161210353, 11678.766424671629, 11890.940545297219, 11914.940425297818, 12063.939680301894, 7804.765857024298, 8009.759707208793, 8020.438569300093, 8546.102659220942, 8769.956150219208, 8745.737627871174, 9267.629294828132, 9519.381240219363, 9590.472524011246, 9860.556274967501, 8417.957910210409, 8560.48637081777, 8592.699255526026, 8618.612162452579, 8692.608832602675, 8545.487270763773, 8555.57222138903, 8573.22840944318, 8659.263962563253, 8681.782955426164, 7530.849383012419, 7557.924420755942, 7597.240275972358, 7603.391728661804, 7604.8479030417975, 5585.245991791056, 5675.233843431084, 5696.344920334214, 5764.42355764437, 5846.0, 5732.168835518743, 5775.942240577709, 5872.882542349043, 5887.939221272692, 5923.614965027259], "value": 4969.434649382875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.77344885997394, 365.2074997255954, 365.8170914542729, 366.50338790938275, 369.3481006024368, 5513.145462453316, 5515.61390702651, 5523.723813809309, 5527.2538207341995, 5584.301962254718, 5274.4725527447235, 5429.647072940256, 5509.807156749514, 5510.669359838413, 5538.75075621597, 4342.652587792969, 4357.411749413836, 4363.454568178978, 4369.798305465991, 4384.912301753963, 4346.174226896896, 4353.390525326457, 4376.496702879178, 4381.605655491006, 4422.292433210687, 4318.244307246235, 4336.176126535948, 4336.6530677545725, 4390.0, 4390.495093064291, 4291.6566674665955, 4324.978375108104, 4327.545607711177, 4327.697061205701, 4345.217860785057, 3901.200253947934, 3904.2777086239134, 3904.668103211202, 3913.2760439318818, 3915.5888631693833, 5278.709670968095, 5304.38999515053, 5344.43883392246, 5378.623496355257, 5384.1923711443205, 4847.248676455155, 4937.703737775709, 4986.376702912136, 5013.498650134994, 5032.547070763649, 5626.0, 5672.461116193955, 5680.176374425727, 5724.0, 5772.249607550992, 4296.31480324797, 4310.698251122422, 4311.956880431187, 4317.266064768965, 4322.33590067823, 5583.274174357308, 5703.942960570383, 5887.587868849182, 5889.050919914499, 5898.469137777621, 4328.783560821947, 4341.826326946887, 4343.0, 4346.195953748566, 4366.519682834885, 4388.341748737715, 4412.514623391425, 4425.889352766142, 4428.98133429313, 4446.888827779267, 4849.369581954324, 4922.507749225048, 4952.331435256195, 4958.628102892298, 4986.152354099811, 5632.831015069554, 5685.403032681592, 5692.715364231772, 5705.62913410627, 5712.228849105318, 1522.276918463743, 1538.4615384615295, 1540.322258206408, 1545.5286137727876, 1548.0, 964.5514835601339, 995.875515560555, 998.7652901568125, 1002.6490728245202, 1029.3463650581784, 4911.45973942866, 4924.507549245047, 4977.90044199114, 5043.713852967469, 5062.670926389773, 5186.533212010937, 5208.723862653598, 5228.503292187236, 5230.790768369298, 5236.450172731885, 4820.0, 4850.369451971292, 4880.316755654211, 5008.499150084962, 5031.77357018935, 2716.8777405016426, 2728.863556822151, 2731.726827317252, 2748.694370174151, 2753.931151721183, 5360.678359298455, 5385.784568617211, 5414.7834086636885, 5437.8368648940595, 5458.2904222451625, 5029.547340739351, 5191.636585439059, 5230.783068412975, 5245.763940622681, 5313.1764576490705, 5323.760430780624, 5331.893362132736, 5339.332583427072, 5375.0, 5386.034097554364, 5140.974295128501, 5161.7677204526635, 5181.35233095863, 5220.738963051908, 5223.294855194652, 4814.494478079891, 5070.51830076157, 5082.263071854634, 5091.338126043591, 5095.974520127375, 5367.302250707383, 5896.6756828374855, 5967.8959392512525, 6079.966405710951, 6102.755889764359, 4339.654707040828, 4361.694681372273, 4390.1878152541885, 4398.384226208397, 4418.6139484677915, 5622.578306626938, 5888.262962426009, 5924.9703751480965, 5929.911051334319, 5937.228160339128, 5702.230198923092, 5713.257276554022, 5716.3140423147825, 5735.369109398046, 5738.770449181986, 5661.773529058792, 5678.68767217807, 5713.7428815704225, 5718.456746609066, 5722.484976352148, 5479.369872464558, 5665.830025099253, 5700.059490184239, 5705.600607957404, 5705.7147142643535, 5722.456366645162, 5740.598158128891, 5751.856203594776, 5755.5683323750045, 5778.797742079007, 5613.915791263053, 5615.831525054255, 5632.760792625616, 5672.858178545569, 5683.403242659461], "value": 4292.799336638633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.9684012639494, 803.987940180897, 815.2947700239291, 839.65574114613, 866.7399780065979, 15980.0, 16571.751423728652, 16574.66850662986, 16581.91709041456, 16588.917055414735, 15335.0, 15623.140727259995, 15624.0, 15647.530574082768, 15664.84335156648, 699.1854489519703, 826.3719573124439, 834.1200033964166, 850.6427300533791, 856.0797143071193, 842.0947481457428, 842.6755699055847, 844.4004756622805, 844.4426678392263, 845.243507061183, 900.3967341880956, 901.3104974694364, 901.4861528928514, 901.7430032440756, 902.0798785239052, 901.197933838884, 901.9774505637346, 903.1465265324272, 903.4127816919022, 903.4940433357341, 660.0297562582996, 660.3726459863101, 662.3707477896018, 662.6653539962289, 662.811098836834, 15145.924270378688, 15161.166135862522, 15168.848311516855, 15208.0, 15210.46763363277, 15994.600134996603, 16444.39567893232, 16620.168991550494, 16899.662006759794, 17182.0, 13003.349832508431, 13014.544490942772, 13093.279869607168, 13444.0, 13647.658808529766, 4087.509498860156, 4100.91798164038, 4135.5657655946, 4183.644390226835, 4212.49450065991, 72179.63910180469, 72951.27048729498, 89517.0, 91042.5447872763, 93078.0, 11599.072074234042, 12218.633440996784, 12223.1443798935, 12572.622821315375, 12596.370181490889, 20270.898645506677, 22820.0, 22848.65727014096, 22852.4286892829, 22887.970041347846, 8544.572771361407, 9021.052789457144, 9167.77080572991, 9194.31042671789, 9214.493202873906, 4976.377952755905, 5117.897642047213, 5191.922121168185, 5299.178627312773, 5447.809326673548, 4069.97965010179, 4199.664026877843, 4215.894602634959, 4224.2185195738275, 4239.51245606753, 702.8699690557263, 705.5202462325668, 709.0498731699547, 709.2694524639527, 733.9596322202224, 641.7112299465166, 689.2349492063805, 817.5013241922422, 834.995825020871, 840.9495430274203, 9995.400275983464, 10706.304090234109, 10901.509432075576, 11396.829047564293, 11468.369239691898, 730.9656835577598, 747.2639450141606, 758.4084414156966, 798.8282519258387, 834.1241696218946, 4795.20879054959, 4857.465678775332, 4966.379202599675, 5075.137226671474, 5188.792448302026, 122301.0, 123474.0, 126006.0, 134607.0, 134825.32587337, 107382.46308768407, 108766.45616772024, 109699.0, 109869.0, 111871.44064279627, 89954.55022724844, 96035.0, 99101.0, 100050.0, 100394.0, 9998.850017249602, 10240.487975601041, 10530.736731581772, 10655.360678359324, 10890.727731806768, 53983.46016539942, 54037.72981135069, 54809.1778623313, 56130.43869561252, 56639.0, 84814.57592711996, 84886.0, 85026.57486712527, 85920.14079859121, 88594.11405885858, 13275.734485310046, 13567.253801041037, 13594.0, 13639.249841258428, 14473.85526144725, 15514.22428878529, 15516.37934482608, 16249.918750406172, 17486.650266994333, 17647.558811029827, 13747.900167986738, 14812.185329806533, 14844.257787110386, 15127.54617361481, 15447.459338923083, 15494.225288735744, 15647.139407332706, 15791.21043947775, 15898.761518577, 16929.91535042317, 14640.341184646504, 15256.694866102394, 15729.606759831096, 15818.60453488672, 16082.59793505125, 11997.40012999364, 12129.150959433107, 12236.632901012983, 12273.938630306791, 12847.807282890952, 12502.437390317276, 12535.561255356019, 12579.0, 12792.042448217826, 12849.614511564669], "value": 8248.488317224252, "unit": "score"}], "name": "dromaeo_css", "value": 7680.695876531729, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 118.0, 109.0, 110.0, 112.0, 111.0, 109.0, 109.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 145.0, 172.0, 114.0, 149.0, 142.0, 117.0, 176.0, 115.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 163.0, 159.0, 161.0, 161.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 166.0, 76.0, 75.0, 75.0, 76.0, 171.0, 74.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 154.0, 84.0, 83.0, 84.0, 143.0, 82.0, 83.0, 147.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 50.0, 51.0, 58.0, 50.0, 49.0, 48.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 59.0, 58.0, 58.0, 58.0, 60.0, 61.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 79.0, 74.0, 74.0, 72.0, 88.0, 74.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 121.0, 123.0, 123.0, 118.0, 117.0, 116.0, 119.0, 178.0, 138.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 149.0, 149.0, 154.0, 151.0, 149.0, 147.0, 151.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 62.0, 61.0, 62.0, 63.0, 60.0, 60.0, 62.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1488.4999999999998, "alertThreshold": 2.0}]} 16:44:40 INFO - Return code: 0 16:44:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:44:40 INFO - # TBPL SUCCESS # 16:44:40 INFO - Running post-action listener: _resource_record_post_action 16:44:40 INFO - [mozharness: 2016-10-24 23:44:40.487544Z] Finished run-tests step (success) 16:44:40 INFO - Running post-run listener: _resource_record_post_run 16:44:40 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 290902016; Read time: 68; Write time: 138252 16:44:40 INFO - TinderboxPrint: CPU usage
13.0% 16:44:40 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 68 16:44:40 INFO - TinderboxPrint: I/O write bytes / time
290,902,016 / 138,252 16:44:40 INFO - TinderboxPrint: CPU idle
8,238.6 (87.0%) 16:44:40 INFO - TinderboxPrint: CPU user
1,186.1 (12.5%) 16:44:40 INFO - TinderboxPrint: Swap in / out
0 / 0 16:44:40 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70094848; Read time: 0; Write time: 32592 16:44:40 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220807168; Read time: 16; Write time: 105660 16:44:41 INFO - Running post-run listener: _upload_blobber_files 16:44:41 INFO - Blob upload gear active. 16:44:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:44:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:44:41 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'] 16:44:41 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 16:44:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:42 INFO - (blobuploader) - INFO - Done attempting. 16:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:44:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:43 INFO - (blobuploader) - INFO - Done attempting. 16:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:45 INFO - (blobuploader) - INFO - Done attempting. 16:44:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:44:45 INFO - Return code: 0 16:44:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:44:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:44:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a713214976347497de16b53612bd9e3efbfb8cbc0d4e8356ef6113c11b70fa8c66de9eeac7014c4a2e748615adf37e5e738ea7e39a0bbd6e4291fbc93ec44e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c92c56b058996cdf895027f939aacee42e82d5ab50d97b9ef6c633d0405e90ca3704e6848c617817be7109a3decd3a2ac4706388d19d1e7b3af9f94406e7bdd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aba4b24b0dfb2ec9e2db926089c2ccf9d3149a5474fc7fab2bd5c385f29762a30493570c141679097f52ae5c078b1cf3f4e309254cda5715c2c75fa027be8c5c"} 16:44:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:44:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:44:46 INFO - Contents: 16:44:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a713214976347497de16b53612bd9e3efbfb8cbc0d4e8356ef6113c11b70fa8c66de9eeac7014c4a2e748615adf37e5e738ea7e39a0bbd6e4291fbc93ec44e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c92c56b058996cdf895027f939aacee42e82d5ab50d97b9ef6c633d0405e90ca3704e6848c617817be7109a3decd3a2ac4706388d19d1e7b3af9f94406e7bdd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aba4b24b0dfb2ec9e2db926089c2ccf9d3149a5474fc7fab2bd5c385f29762a30493570c141679097f52ae5c078b1cf3f4e309254cda5715c2c75fa027be8c5c"} 16:44:46 INFO - Running post-run listener: copy_logs_to_upload_dir 16:44:46 INFO - Copying logs to upload dir... 16:44:46 INFO - mkdir: /builds/slave/test/build/upload/logs 16:44:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.061961 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-24 16:44:45.807549) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 16:44:45.810852) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 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/a713214976347497de16b53612bd9e3efbfb8cbc0d4e8356ef6113c11b70fa8c66de9eeac7014c4a2e748615adf37e5e738ea7e39a0bbd6e4291fbc93ec44e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c92c56b058996cdf895027f939aacee42e82d5ab50d97b9ef6c633d0405e90ca3704e6848c617817be7109a3decd3a2ac4706388d19d1e7b3af9f94406e7bdd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aba4b24b0dfb2ec9e2db926089c2ccf9d3149a5474fc7fab2bd5c385f29762a30493570c141679097f52ae5c078b1cf3f4e309254cda5715c2c75fa027be8c5c"} build_url:https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/Ld5TwU0cTHmEOWKM7oAayg/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/a713214976347497de16b53612bd9e3efbfb8cbc0d4e8356ef6113c11b70fa8c66de9eeac7014c4a2e748615adf37e5e738ea7e39a0bbd6e4291fbc93ec44e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c92c56b058996cdf895027f939aacee42e82d5ab50d97b9ef6c633d0405e90ca3704e6848c617817be7109a3decd3a2ac4706388d19d1e7b3af9f94406e7bdd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aba4b24b0dfb2ec9e2db926089c2ccf9d3149a5474fc7fab2bd5c385f29762a30493570c141679097f52ae5c078b1cf3f4e309254cda5715c2c75fa027be8c5c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 16:44:45.836220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:44:45.836607) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1477351011.385732-2042102280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 16:44:45.922569) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 16:44:45.922899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 16:44:45.923218) ========= ========= Total master_lag: 0.16 =========