builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1472794048.22 results: success (0) buildid: 20160901191942 builduid: c42224b832d641f0833c687403313e9e revision: 6ea5133aab19860080b3d8b2221c2d5b5c17a465 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.216474) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.216994) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.217317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.234568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.235013) ========= 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=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 22:27:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-09-01 22:27:29 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121275 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.381316) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.381663) ========= 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=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021332 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.436540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:28.436921) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ea5133aab19860080b3d8b2221c2d5b5c17a465 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ea5133aab19860080b3d8b2221c2d5b5c17a465 --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=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 22:27:29,922 truncating revision to first 12 chars 2016-09-01 22:27:29,922 Setting DEBUG logging. 2016-09-01 22:27:29,922 attempt 1/10 2016-09-01 22:27:29,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6ea5133aab19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 22:27:30,226 unpacking tar archive at: mozilla-aurora-6ea5133aab19/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631381 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:29.090681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:29.091007) ========= script_repo_revision: 6ea5133aab19860080b3d8b2221c2d5b5c17a465 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:29.091465) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:29.091804) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 22:27:29.102842) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-01 22:27:29.103157) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:27:30 INFO - MultiFileLogger online at 20160901 22:27:30 in /builds/slave/test 22:27:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:27:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:27:30 INFO - {'append_to_log': False, 22:27:30 INFO - 'base_work_dir': '/builds/slave/test', 22:27:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:27:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:27:30 INFO - 'branch': 'Mozilla-Aurora', 22:27:30 INFO - 'buildbot_json_path': 'buildprops.json', 22:27:30 INFO - 'config_files': ('talos/linux_config.py',), 22:27:30 INFO - 'default_actions': ('clobber', 22:27:30 INFO - 'read-buildbot-config', 22:27:30 INFO - 'download-and-extract', 22:27:30 INFO - 'populate-webroot', 22:27:30 INFO - 'create-virtualenv', 22:27:30 INFO - 'install', 22:27:30 INFO - 'run-tests'), 22:27:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:27:30 INFO - 'download_minidump_stackwalk': True, 22:27:30 INFO - 'download_symbols': 'ondemand', 22:27:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:27:30 INFO - 'tooltool.py': '/tools/tooltool.py', 22:27:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:27:30 INFO - '/tools/misc-python/virtualenv.py')}, 22:27:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:27:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:27:30 INFO - 'installer_path': 'installer.exe', 22:27:30 INFO - 'log_level': 'info', 22:27:30 INFO - 'log_name': 'talos', 22:27:30 INFO - 'log_to_console': True, 22:27:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:27:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:27:30 INFO - 'opt_config_files': (), 22:27:30 INFO - 'pip_index': False, 22:27:30 INFO - 'sps_profile': False, 22:27:30 INFO - 'sps_profile_interval': 0, 22:27:30 INFO - 'suite': 'dromaeojs', 22:27:30 INFO - 'system_bits': '32', 22:27:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:27:30 INFO - 'title': 'talos-linux64-ix-056', 22:27:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:27:30 INFO - 'use_talos_json': True, 22:27:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:27:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:27:30 INFO - 'work_dir': 'build'} 22:27:30 INFO - [mozharness: 2016-09-02 05:27:30.690257Z] Running clobber step. 22:27:30 INFO - Running pre-action listener: _resource_record_pre_action 22:27:30 INFO - Running main action method: clobber 22:27:30 INFO - rmtree: /builds/slave/test/build 22:27:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:27:31 INFO - Running post-action listener: _resource_record_post_action 22:27:31 INFO - [mozharness: 2016-09-02 05:27:31.669818Z] Finished clobber step (success) 22:27:31 INFO - [mozharness: 2016-09-02 05:27:31.669917Z] Running read-buildbot-config step. 22:27:31 INFO - Running pre-action listener: _resource_record_pre_action 22:27:31 INFO - Running main action method: read_buildbot_config 22:27:31 INFO - Using buildbot properties: 22:27:31 INFO - { 22:27:31 INFO - "project": "", 22:27:31 INFO - "product": "firefox", 22:27:31 INFO - "script_repo_revision": "production", 22:27:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:27:31 INFO - "repository": "", 22:27:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:27:31 INFO - "buildid": "20160901191942", 22:27:31 INFO - "pgo_build": "True", 22:27:31 INFO - "basedir": "/builds/slave/test", 22:27:31 INFO - "buildnumber": 726, 22:27:31 INFO - "slavename": "talos-linux64-ix-056", 22:27:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:27:31 INFO - "platform": "ubuntu64_hw", 22:27:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:27:31 INFO - "branch": "mozilla-aurora", 22:27:31 INFO - "repo_path": "releases/mozilla-aurora", 22:27:31 INFO - "revision": "6ea5133aab19860080b3d8b2221c2d5b5c17a465", 22:27:31 INFO - "stage_platform": "linux64-pgo", 22:27:31 INFO - "builduid": "c42224b832d641f0833c687403313e9e", 22:27:31 INFO - "slavebuilddir": "test" 22:27:31 INFO - } 22:27:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 22:27:31 INFO - Running post-action listener: _resource_record_post_action 22:27:31 INFO - [mozharness: 2016-09-02 05:27:31.672332Z] Finished read-buildbot-config step (success) 22:27:31 INFO - [mozharness: 2016-09-02 05:27:31.672419Z] Running download-and-extract step. 22:27:31 INFO - Running pre-action listener: _resource_record_pre_action 22:27:31 INFO - Running main action method: download_and_extract 22:27:31 INFO - mkdir: /builds/slave/test/build/tests 22:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:31 INFO - https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:27:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:27:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:27:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:27:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:27:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:27:31 INFO - retry: Failed, sleeping 30 seconds before retrying 22:28:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 22:28:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:28:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:28:01 INFO - retry: Failed, sleeping 60 seconds before retrying 22:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 22:29:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:29:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:29:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 22:29:01 INFO - Caught exception: HTTP Error 404: Not Found 22:29:01 INFO - Caught exception: HTTP Error 404: Not Found 22:29:01 INFO - Caught exception: HTTP Error 404: Not Found 22:29:01 INFO - trying https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:29:01 INFO - Downloading https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:29:02 INFO - Downloaded 1361 bytes. 22:29:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:29:02 INFO - Using the following test package requirements: 22:29:02 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:29:02 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:29:02 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:29:02 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'jsshell-linux-x86_64.zip'], 22:29:02 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:29:02 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:29:02 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:29:02 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:29:02 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:29:02 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:29:02 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:29:02 INFO - https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:29:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:29:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:29:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:29:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:29:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:29:02 INFO - retry: Failed, sleeping 30 seconds before retrying 22:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:29:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:29:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:29:32 INFO - retry: Failed, sleeping 60 seconds before retrying 22:30:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:30:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:30:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 22:30:32 INFO - Caught exception: HTTP Error 404: Not Found 22:30:32 INFO - Caught exception: HTTP Error 404: Not Found 22:30:32 INFO - Caught exception: HTTP Error 404: Not Found 22:30:32 INFO - trying https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:32 INFO - Downloading https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:33 INFO - Downloaded 21771390 bytes. 22:30:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:30:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:30:34 INFO - Return code: 0 22:30:34 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:30: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')]} 22:30:34 INFO - https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:30:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:30:34 INFO - retry: Failed, sleeping 30 seconds before retrying 22:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:31:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:31:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:31:04 INFO - retry: Failed, sleeping 60 seconds before retrying 22:32:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:32:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:32:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:32:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 22:32:04 INFO - Caught exception: HTTP Error 404: Not Found 22:32:04 INFO - Caught exception: HTTP Error 404: Not Found 22:32:04 INFO - Caught exception: HTTP Error 404: Not Found 22:32:04 INFO - trying https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:32:04 INFO - Downloading https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:32:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:32:05 INFO - Downloaded 21771390 bytes. 22:32:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:32:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:32:06 INFO - Return code: 0 22:32:06 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')]} 22:32:06 INFO - https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:32:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:32:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:32:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:32:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:32:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:32:06 INFO - retry: Failed, sleeping 30 seconds before retrying 22:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 22:32:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:32:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:32:36 INFO - retry: Failed, sleeping 60 seconds before retrying 22:33:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 22:33:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:33:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:33:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 22:33:36 INFO - Caught exception: HTTP Error 404: Not Found 22:33:36 INFO - Caught exception: HTTP Error 404: Not Found 22:33:36 INFO - Caught exception: HTTP Error 404: Not Found 22:33:36 INFO - trying https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:33:36 INFO - Downloading https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:33:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:33:37 INFO - Downloaded 14413740 bytes. 22:33:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:33:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:33:37 INFO - Return code: 0 22:33: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')]} 22:33:37 INFO - https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:33:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:33:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:33:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:33:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:33:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:33:38 INFO - retry: Failed, sleeping 30 seconds before retrying 22:34:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 22:34:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:34:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:34:08 INFO - retry: Failed, sleeping 60 seconds before retrying 22:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 22:35:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:35:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:35:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 22:35:08 INFO - Caught exception: HTTP Error 404: Not Found 22:35:08 INFO - Caught exception: HTTP Error 404: Not Found 22:35:08 INFO - Caught exception: HTTP Error 404: Not Found 22:35:08 INFO - trying https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:35:08 INFO - Downloading https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:35:09 INFO - Downloaded 63899813 bytes. 22:35:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:35:09 INFO - mkdir: /builds/slave/test/properties 22:35:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:35:09 INFO - Writing to file /builds/slave/test/properties/build_url 22:35:09 INFO - Contents: 22:35:09 INFO - build_url:https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:35:10 INFO - Running post-action listener: _resource_record_post_action 22:35:10 INFO - Running post-action listener: set_extra_try_arguments 22:35:10 INFO - [mozharness: 2016-09-02 05:35:10.410544Z] Finished download-and-extract step (success) 22:35:10 INFO - [mozharness: 2016-09-02 05:35:10.410774Z] Running populate-webroot step. 22:35:10 INFO - Running pre-action listener: _resource_record_pre_action 22:35:10 INFO - Running main action method: populate_webroot 22:35:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:35:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:35:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:35:10 INFO - '--tppagecycles', 22:35:10 INFO - '7'], 22:35:10 INFO - 'tests': ['tsvgm']}}, 22:35:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:35:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:35:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:35:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:35:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:35:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:35:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['damp', 'tps']}, 22:35:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'tests': ['damp', 'tps']}, 22:35:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['dromaeo_dom']}, 22:35:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:35:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['basic_compositor_video']}, 22:35:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:35:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'other-e10s': {'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['a11yr', 22:35:10 INFO - 'ts_paint', 22:35:10 INFO - 'tpaint', 22:35:10 INFO - 'sessionrestore', 22:35:10 INFO - 'sessionrestore_no_auto_restore', 22:35:10 INFO - 'tabpaint']}, 22:35:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['tsvgx', 22:35:10 INFO - 'tsvgr_opacity', 22:35:10 INFO - 'tart', 22:35:10 INFO - 'tscrollx', 22:35:10 INFO - 'cart']}, 22:35:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:35:10 INFO - 'tsvgr_opacity', 22:35:10 INFO - 'tart', 22:35:10 INFO - 'tscrollx', 22:35:10 INFO - 'cart']}, 22:35:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'talos_options': ['--disable-e10s'], 22:35:10 INFO - 'tests': ['tp5o']}, 22:35:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'tests': ['tp5o']}, 22:35:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'talos_options': ['--disable-e10s', 22:35:10 INFO - '--xperf_path', 22:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:35:10 INFO - 'tests': ['tp5n']}, 22:35:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:35:10 INFO - 'talos_options': ['--xperf_path', 22:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:35:10 INFO - 'tests': ['tp5n']}}, 22:35:10 INFO - 'talos.zip': {'path': '', 22:35:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:35:10 INFO - Running post-action listener: _resource_record_post_action 22:35:10 INFO - [mozharness: 2016-09-02 05:35:10.430860Z] Finished populate-webroot step (success) 22:35:10 INFO - [mozharness: 2016-09-02 05:35:10.430948Z] Running create-virtualenv step. 22:35:10 INFO - Running pre-action listener: _resource_record_pre_action 22:35:10 INFO - Running main action method: create_virtualenv 22:35:10 INFO - Creating virtualenv /builds/slave/test/build/venv 22:35:10 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 22:35:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:35:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:35:10 INFO - Using real prefix '/usr' 22:35:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:35:11 INFO - Installing distribute.............................................................................................................................................................................................done. 22:35:12 INFO - Installing pip.................done. 22:35:13 INFO - Return code: 0 22:35:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:35:13 INFO - Using env: {'DISPLAY': ':0', 22:35:13 INFO - 'HOME': '/home/cltbld', 22:35:13 INFO - 'LANG': 'en_US.UTF-8', 22:35:13 INFO - 'LANGUAGE': 'en_US:en', 22:35:13 INFO - 'LOGNAME': 'cltbld', 22:35:13 INFO - 'MAIL': '/var/mail/cltbld', 22:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:13 INFO - 'MOZ_NO_REMOTE': '1', 22:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:13 INFO - 'NO_EM_RESTART': '1', 22:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:13 INFO - 'PWD': '/builds/slave/test', 22:35:13 INFO - 'SHELL': '/bin/bash', 22:35:13 INFO - 'SHLVL': '1', 22:35:13 INFO - 'TERM': 'linux', 22:35:13 INFO - 'TMOUT': '86400', 22:35:13 INFO - 'USER': 'cltbld', 22:35:13 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:13 INFO - '_': '/tools/buildbot/bin/python'} 22:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:13 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 22:35:13 INFO - Cleaning up... 22:35:13 INFO - Return code: 0 22:35:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:35:13 INFO - Using env: {'DISPLAY': ':0', 22:35:13 INFO - 'HOME': '/home/cltbld', 22:35:13 INFO - 'LANG': 'en_US.UTF-8', 22:35:13 INFO - 'LANGUAGE': 'en_US:en', 22:35:13 INFO - 'LOGNAME': 'cltbld', 22:35:13 INFO - 'MAIL': '/var/mail/cltbld', 22:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:13 INFO - 'MOZ_NO_REMOTE': '1', 22:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:13 INFO - 'NO_EM_RESTART': '1', 22:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:13 INFO - 'PWD': '/builds/slave/test', 22:35:13 INFO - 'SHELL': '/bin/bash', 22:35:13 INFO - 'SHLVL': '1', 22:35:13 INFO - 'TERM': 'linux', 22:35:13 INFO - 'TMOUT': '86400', 22:35:13 INFO - 'USER': 'cltbld', 22:35:13 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:13 INFO - '_': '/tools/buildbot/bin/python'} 22:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:13 INFO - Downloading/unpacking psutil>=3.1.1 22:35:13 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 22:35:13 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 22:35:13 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 22:35:13 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 22:35:13 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 22:35:13 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 22:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:35:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:35:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:35:15 INFO - Installing collected packages: psutil 22:35:15 INFO - Running setup.py install for psutil 22:35:15 INFO - building 'psutil._psutil_linux' extension 22:35:15 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 22:35:15 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 22:35:15 INFO - building 'psutil._psutil_posix' extension 22:35:15 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 22:35:15 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 22:35:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:35:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:35:15 INFO - Successfully installed psutil 22:35:15 INFO - Cleaning up... 22:35:15 INFO - Return code: 0 22:35:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:35:15 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')]} 22:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:15 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')]} 22:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:15 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:15 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 22:35:15 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 22:35:15 INFO - Using env: {'DISPLAY': ':0', 22:35:15 INFO - 'HOME': '/home/cltbld', 22:35:15 INFO - 'LANG': 'en_US.UTF-8', 22:35:15 INFO - 'LANGUAGE': 'en_US:en', 22:35:15 INFO - 'LOGNAME': 'cltbld', 22:35:15 INFO - 'MAIL': '/var/mail/cltbld', 22:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:15 INFO - 'MOZ_NO_REMOTE': '1', 22:35:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:15 INFO - 'NO_EM_RESTART': '1', 22:35:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:15 INFO - 'PWD': '/builds/slave/test', 22:35:15 INFO - 'SHELL': '/bin/bash', 22:35:15 INFO - 'SHLVL': '1', 22:35:15 INFO - 'TERM': 'linux', 22:35:15 INFO - 'TMOUT': '86400', 22:35:15 INFO - 'USER': 'cltbld', 22:35:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:15 INFO - '_': '/tools/buildbot/bin/python'} 22:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:35:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:35:18 INFO - Installing collected packages: mozsystemmonitor 22:35:18 INFO - Running setup.py install for mozsystemmonitor 22:35:18 INFO - Successfully installed mozsystemmonitor 22:35:18 INFO - Cleaning up... 22:35:18 INFO - Return code: 0 22:35:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:35:18 INFO - Using env: {'DISPLAY': ':0', 22:35:18 INFO - 'HOME': '/home/cltbld', 22:35:18 INFO - 'LANG': 'en_US.UTF-8', 22:35:18 INFO - 'LANGUAGE': 'en_US:en', 22:35:18 INFO - 'LOGNAME': 'cltbld', 22:35:18 INFO - 'MAIL': '/var/mail/cltbld', 22:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:18 INFO - 'MOZ_NO_REMOTE': '1', 22:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:18 INFO - 'NO_EM_RESTART': '1', 22:35:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:18 INFO - 'PWD': '/builds/slave/test', 22:35:18 INFO - 'SHELL': '/bin/bash', 22:35:18 INFO - 'SHLVL': '1', 22:35:18 INFO - 'TERM': 'linux', 22:35:18 INFO - 'TMOUT': '86400', 22:35:18 INFO - 'USER': 'cltbld', 22:35:18 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:18 INFO - '_': '/tools/buildbot/bin/python'} 22:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:18 INFO - Downloading/unpacking jsonschema==2.5.1 22:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:35:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:20 INFO - Downloading functools32-3.2.3-2.tar.gz 22:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:35:20 INFO - warning: no files found matching '*.txt' 22:35:20 INFO - no previously-included directories found matching 'build' 22:35:20 INFO - no previously-included directories found matching 'dist' 22:35:20 INFO - no previously-included directories found matching '.git*' 22:35:20 INFO - Installing collected packages: jsonschema, functools32 22:35:20 INFO - Running setup.py install for functools32 22:35:20 INFO - warning: no files found matching '*.txt' 22:35:20 INFO - no previously-included directories found matching 'build' 22:35:20 INFO - no previously-included directories found matching 'dist' 22:35:20 INFO - no previously-included directories found matching '.git*' 22:35:20 INFO - Successfully installed jsonschema functools32 22:35:20 INFO - Cleaning up... 22:35:20 INFO - Return code: 0 22:35:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:35:20 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')]} 22:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:20 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')]} 22:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:20 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:20 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 22:35:20 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 22:35:20 INFO - Using env: {'DISPLAY': ':0', 22:35:20 INFO - 'HOME': '/home/cltbld', 22:35:20 INFO - 'LANG': 'en_US.UTF-8', 22:35:20 INFO - 'LANGUAGE': 'en_US:en', 22:35:20 INFO - 'LOGNAME': 'cltbld', 22:35:20 INFO - 'MAIL': '/var/mail/cltbld', 22:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:20 INFO - 'MOZ_NO_REMOTE': '1', 22:35:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:20 INFO - 'NO_EM_RESTART': '1', 22:35:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:20 INFO - 'PWD': '/builds/slave/test', 22:35:20 INFO - 'SHELL': '/bin/bash', 22:35:20 INFO - 'SHLVL': '1', 22:35:20 INFO - 'TERM': 'linux', 22:35:20 INFO - 'TMOUT': '86400', 22:35:20 INFO - 'USER': 'cltbld', 22:35:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:20 INFO - '_': '/tools/buildbot/bin/python'} 22:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:35:21 INFO - Cleaning up... 22:35:21 INFO - Return code: 0 22:35:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:21 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:21 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 22:35:21 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 22:35:21 INFO - Using env: {'DISPLAY': ':0', 22:35:21 INFO - 'HOME': '/home/cltbld', 22:35:21 INFO - 'LANG': 'en_US.UTF-8', 22:35:21 INFO - 'LANGUAGE': 'en_US:en', 22:35:21 INFO - 'LOGNAME': 'cltbld', 22:35:21 INFO - 'MAIL': '/var/mail/cltbld', 22:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:21 INFO - 'MOZ_NO_REMOTE': '1', 22:35:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:21 INFO - 'NO_EM_RESTART': '1', 22:35:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:21 INFO - 'PWD': '/builds/slave/test', 22:35:21 INFO - 'SHELL': '/bin/bash', 22:35:21 INFO - 'SHLVL': '1', 22:35:21 INFO - 'TERM': 'linux', 22:35:21 INFO - 'TMOUT': '86400', 22:35:21 INFO - 'USER': 'cltbld', 22:35:21 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:21 INFO - '_': '/tools/buildbot/bin/python'} 22:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:21 INFO - Downloading/unpacking blobuploader==1.2.4 22:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:23 INFO - Downloading blobuploader-1.2.4.tar.gz 22:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:35:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:35:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 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 22:35:23 INFO - Downloading docopt-0.6.1.tar.gz 22:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:35:23 INFO - Installing collected packages: blobuploader, requests, docopt 22:35:23 INFO - Running setup.py install for blobuploader 22:35:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:35:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:35:24 INFO - Running setup.py install for requests 22:35:24 INFO - Running setup.py install for docopt 22:35:24 INFO - Successfully installed blobuploader requests docopt 22:35:24 INFO - Cleaning up... 22:35:24 INFO - Return code: 0 22:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:35: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')]} 22:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35: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')]} 22:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:24 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:24 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 22:35:24 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 22:35:24 INFO - Using env: {'DISPLAY': ':0', 22:35:24 INFO - 'HOME': '/home/cltbld', 22:35:24 INFO - 'LANG': 'en_US.UTF-8', 22:35:24 INFO - 'LANGUAGE': 'en_US:en', 22:35:24 INFO - 'LOGNAME': 'cltbld', 22:35:24 INFO - 'MAIL': '/var/mail/cltbld', 22:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:24 INFO - 'MOZ_NO_REMOTE': '1', 22:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:24 INFO - 'NO_EM_RESTART': '1', 22:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:24 INFO - 'PWD': '/builds/slave/test', 22:35:24 INFO - 'SHELL': '/bin/bash', 22:35:24 INFO - 'SHLVL': '1', 22:35:24 INFO - 'TERM': 'linux', 22:35:24 INFO - 'TMOUT': '86400', 22:35:24 INFO - 'USER': 'cltbld', 22:35:24 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:24 INFO - '_': '/tools/buildbot/bin/python'} 22:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:35:24 INFO - Running setup.py (path:/tmp/pip-ajGjzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:35:24 INFO - Running setup.py (path:/tmp/pip-BHlDMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:35:24 INFO - Running setup.py (path:/tmp/pip-OEMkCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:35:25 INFO - Running setup.py (path:/tmp/pip-yp0ypn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:35:25 INFO - Running setup.py (path:/tmp/pip-Ezlg4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:35:25 INFO - Running setup.py (path:/tmp/pip-Fl4SLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:35:25 INFO - Running setup.py (path:/tmp/pip-nDNG7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:35:25 INFO - Running setup.py (path:/tmp/pip-kHtrTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:35:25 INFO - Running setup.py (path:/tmp/pip-YkgUx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:35:25 INFO - Running setup.py (path:/tmp/pip-9s8_hI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:35:25 INFO - Running setup.py (path:/tmp/pip-xAIuz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:35:25 INFO - Running setup.py (path:/tmp/pip-L7H9Nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:35:25 INFO - Running setup.py (path:/tmp/pip-WoPPRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:35:25 INFO - Running setup.py (path:/tmp/pip-q4u9PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:35:25 INFO - Running setup.py (path:/tmp/pip-1v5sEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:35:25 INFO - Running setup.py (path:/tmp/pip-79FTTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:35:25 INFO - Running setup.py (path:/tmp/pip-67tL7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:35:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:35:26 INFO - Running setup.py install for manifestparser 22:35:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Running setup.py install for mozcrash 22:35:26 INFO - Running setup.py install for mozdebug 22:35:26 INFO - Running setup.py install for mozdevice 22:35:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Running setup.py install for mozfile 22:35:26 INFO - Running setup.py install for mozhttpd 22:35:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Running setup.py install for mozinfo 22:35:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Running setup.py install for mozInstall 22:35:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:35:26 INFO - Running setup.py install for mozleak 22:35:26 INFO - Running setup.py install for mozlog 22:35:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Running setup.py install for moznetwork 22:35:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Running setup.py install for mozprocess 22:35:27 INFO - Running setup.py install for mozprofile 22:35:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Running setup.py install for mozrunner 22:35:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Running setup.py install for mozscreenshot 22:35:27 INFO - Running setup.py install for moztest 22:35:27 INFO - Running setup.py install for mozversion 22:35:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:35:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:35:27 INFO - Cleaning up... 22:35:27 INFO - Return code: 0 22:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:35:27 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')]} 22:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:27 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')]} 22:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:27 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:27 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 22:35:27 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 22:35:27 INFO - Using env: {'DISPLAY': ':0', 22:35:27 INFO - 'HOME': '/home/cltbld', 22:35:27 INFO - 'LANG': 'en_US.UTF-8', 22:35:27 INFO - 'LANGUAGE': 'en_US:en', 22:35:27 INFO - 'LOGNAME': 'cltbld', 22:35:27 INFO - 'MAIL': '/var/mail/cltbld', 22:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:27 INFO - 'MOZ_NO_REMOTE': '1', 22:35:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:27 INFO - 'NO_EM_RESTART': '1', 22:35:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:27 INFO - 'PWD': '/builds/slave/test', 22:35:27 INFO - 'SHELL': '/bin/bash', 22:35:27 INFO - 'SHLVL': '1', 22:35:27 INFO - 'TERM': 'linux', 22:35:27 INFO - 'TMOUT': '86400', 22:35:27 INFO - 'USER': 'cltbld', 22:35:27 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:27 INFO - '_': '/tools/buildbot/bin/python'} 22:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:35:28 INFO - Running setup.py (path:/tmp/pip-DT7jB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:35:28 INFO - Running setup.py (path:/tmp/pip-9rHLC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:35:28 INFO - Running setup.py (path:/tmp/pip-SJziBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:35:28 INFO - Running setup.py (path:/tmp/pip-ApmXkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:35:28 INFO - Running setup.py (path:/tmp/pip-CBoeaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:35:28 INFO - Running setup.py (path:/tmp/pip-zfyG_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:35:28 INFO - Running setup.py (path:/tmp/pip-62fG95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:35:28 INFO - Running setup.py (path:/tmp/pip-N7xqMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:35:28 INFO - Running setup.py (path:/tmp/pip-EDh6PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:35:28 INFO - Running setup.py (path:/tmp/pip-sk53rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:35:28 INFO - Running setup.py (path:/tmp/pip-Pd75dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:35:28 INFO - Running setup.py (path:/tmp/pip-LSw7Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:35:28 INFO - Running setup.py (path:/tmp/pip-tye8FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:35:28 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)) 22:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:35:29 INFO - Running setup.py (path:/tmp/pip-7kILlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:35:29 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)) 22:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:35:29 INFO - Running setup.py (path:/tmp/pip-FiUk1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:35:29 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)) 22:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:35:29 INFO - Running setup.py (path:/tmp/pip-90IcBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:35:29 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)) 22:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:35:29 INFO - Running setup.py (path:/tmp/pip-rDevEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:35:29 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)) 22:35:29 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)) 22:35:29 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)) 22:35:29 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)) 22:35:29 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)) 22:35:29 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)) 22:35:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:35:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:35:31 INFO - Downloading blessings-1.6.tar.gz 22:35:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:35:31 INFO - Installing collected packages: blessings 22:35:31 INFO - Running setup.py install for blessings 22:35:31 INFO - Successfully installed blessings 22:35:31 INFO - Cleaning up... 22:35:31 INFO - Return code: 0 22:35:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:35:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:35:31 INFO - Reading from file tmpfile_stdout 22:35:31 INFO - Current package versions: 22:35:31 INFO - argparse == 1.2.1 22:35:31 INFO - blessings == 1.6 22:35:31 INFO - blobuploader == 1.2.4 22:35:31 INFO - docopt == 0.6.1 22:35:31 INFO - functools32 == 3.2.3-2 22:35:31 INFO - jsonschema == 2.5.1 22:35:31 INFO - manifestparser == 1.1 22:35:31 INFO - mozInstall == 1.12 22:35:31 INFO - mozcrash == 0.17 22:35:31 INFO - mozdebug == 0.1 22:35:31 INFO - mozdevice == 0.48 22:35:31 INFO - mozfile == 1.2 22:35:31 INFO - mozhttpd == 0.7 22:35:31 INFO - mozinfo == 0.9 22:35:31 INFO - mozleak == 0.1 22:35:31 INFO - mozlog == 3.3 22:35:31 INFO - moznetwork == 0.27 22:35:31 INFO - mozprocess == 0.23 22:35:31 INFO - mozprofile == 0.28 22:35:31 INFO - mozrunner == 6.12 22:35:31 INFO - mozscreenshot == 0.1 22:35:31 INFO - mozsystemmonitor == 0.3 22:35:31 INFO - moztest == 0.7 22:35:31 INFO - mozversion == 1.4 22:35:31 INFO - psutil == 3.1.1 22:35:31 INFO - requests == 1.2.3 22:35:31 INFO - wsgiref == 0.1.2 22:35:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:31 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:31 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 22:35:31 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 22:35:31 INFO - Using env: {'DISPLAY': ':0', 22:35:31 INFO - 'HOME': '/home/cltbld', 22:35:31 INFO - 'LANG': 'en_US.UTF-8', 22:35:31 INFO - 'LANGUAGE': 'en_US:en', 22:35:31 INFO - 'LOGNAME': 'cltbld', 22:35:31 INFO - 'MAIL': '/var/mail/cltbld', 22:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:31 INFO - 'MOZ_NO_REMOTE': '1', 22:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:31 INFO - 'NO_EM_RESTART': '1', 22:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:31 INFO - 'PWD': '/builds/slave/test', 22:35:31 INFO - 'SHELL': '/bin/bash', 22:35:31 INFO - 'SHLVL': '1', 22:35:31 INFO - 'TERM': 'linux', 22:35:31 INFO - 'TMOUT': '86400', 22:35:31 INFO - 'USER': 'cltbld', 22:35:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:31 INFO - '_': '/tools/buildbot/bin/python'} 22:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 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)) 22:35:31 INFO - Cleaning up... 22:35:31 INFO - Return code: 0 22:35:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:35:31 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 0xc95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, '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': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', '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 22:35:31 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 22:35:31 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 22:35:31 INFO - Using env: {'DISPLAY': ':0', 22:35:31 INFO - 'HOME': '/home/cltbld', 22:35:31 INFO - 'LANG': 'en_US.UTF-8', 22:35:31 INFO - 'LANGUAGE': 'en_US:en', 22:35:31 INFO - 'LOGNAME': 'cltbld', 22:35:31 INFO - 'MAIL': '/var/mail/cltbld', 22:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:31 INFO - 'MOZ_NO_REMOTE': '1', 22:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:31 INFO - 'NO_EM_RESTART': '1', 22:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:31 INFO - 'PWD': '/builds/slave/test', 22:35:31 INFO - 'SHELL': '/bin/bash', 22:35:31 INFO - 'SHLVL': '1', 22:35:31 INFO - 'TERM': 'linux', 22:35:31 INFO - 'TMOUT': '86400', 22:35:31 INFO - 'USER': 'cltbld', 22:35:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:31 INFO - '_': '/tools/buildbot/bin/python'} 22:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:35:32 INFO - Cleaning up... 22:35:32 INFO - Return code: 0 22:35:32 INFO - Running post-action listener: _resource_record_post_action 22:35:32 INFO - Running post-action listener: _start_resource_monitoring 22:35:32 INFO - Starting resource monitoring. 22:35:32 INFO - [mozharness: 2016-09-02 05:35:32.130998Z] Finished create-virtualenv step (success) 22:35:32 INFO - [mozharness: 2016-09-02 05:35:32.131432Z] Running install step. 22:35:32 INFO - Running pre-action listener: _resource_record_pre_action 22:35:32 INFO - Running main action method: install 22:35:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:35:32 INFO - Reading from file tmpfile_stdout 22:35:32 INFO - Detecting whether we're running mozinstall >=1.0... 22:35:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:35:32 INFO - Reading from file tmpfile_stdout 22:35:32 INFO - Output received: 22:35:32 INFO - Usage: mozinstall [options] installer 22:35:32 INFO - Options: 22:35:32 INFO - -h, --help show this help message and exit 22:35:32 INFO - -d DEST, --destination=DEST 22:35:32 INFO - Directory to install application into. [default: 22:35:32 INFO - "/builds/slave/test"] 22:35:32 INFO - --app=APP Application being installed. [default: firefox] 22:35:32 INFO - mkdir: /builds/slave/test/build/application 22:35:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:35:49 INFO - Reading from file tmpfile_stdout 22:35:49 INFO - Output received: 22:35:49 INFO - /builds/slave/test/build/application/firefox/firefox 22:35:49 INFO - Running post-action listener: _resource_record_post_action 22:35:49 INFO - [mozharness: 2016-09-02 05:35:49.383917Z] Finished install step (success) 22:35:49 INFO - [mozharness: 2016-09-02 05:35:49.384196Z] Running run-tests step. 22:35:49 INFO - Running pre-action listener: _resource_record_pre_action 22:35:49 INFO - Running main action method: run_tests 22:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:35:49 INFO - Python 2.7.3 22:35:49 INFO - Return code: 0 22:35:49 INFO - grabbing minidump binary from tooltool 22:35: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')]} 22:35:49 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 0xf543b0>, '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 0xf552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf55740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:35:49 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 22:35:49 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 22:35:49 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 22:35:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:35:49 INFO - Return code: 0 22:35:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:35:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:35:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:35:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:35:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 22:35:49 INFO - Using env: {'DISPLAY': ':0', 22:35:49 INFO - 'HOME': '/home/cltbld', 22:35:49 INFO - 'LANG': 'en_US.UTF-8', 22:35:49 INFO - 'LANGUAGE': 'en_US:en', 22:35:49 INFO - 'LOGNAME': 'cltbld', 22:35:49 INFO - 'MAIL': '/var/mail/cltbld', 22:35:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:35:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:35:49 INFO - 'MOZ_NO_REMOTE': '1', 22:35:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:35:49 INFO - 'NO_EM_RESTART': '1', 22:35:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:35:49 INFO - 'PWD': '/builds/slave/test', 22:35:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:35:49 INFO - 'SHELL': '/bin/bash', 22:35:49 INFO - 'SHLVL': '1', 22:35:49 INFO - 'TERM': 'linux', 22:35:49 INFO - 'TMOUT': '86400', 22:35:49 INFO - 'USER': 'cltbld', 22:35:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885', 22:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:35:49 INFO - '_': '/tools/buildbot/bin/python'} 22:35:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:35:49 INFO - mozversion application_buildid: 20160901191942 22:35:49 INFO - mozversion application_changeset: 6ea5133aab19860080b3d8b2221c2d5b5c17a465 22:35:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:35:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:35:49 INFO - mozversion application_name: Firefox 22:35:49 INFO - mozversion application_remotingname: firefox-dev 22:35:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:35:49 INFO - mozversion application_vendor: Mozilla 22:35:49 INFO - mozversion application_version: 50.0a2 22:35:49 INFO - mozversion platform_buildid: 20160901191942 22:35:49 INFO - mozversion platform_changeset: 6ea5133aab19860080b3d8b2221c2d5b5c17a465 22:35:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:35:49 INFO - mozversion platform_version: 50.0a2 22:35:49 INFO - using testdate: 1472794549 22:35:49 INFO - actual date: 1472794549 22:35:49 INFO - starting webserver on 'localhost:51253' 22:35:49 INFO - SUITE-START | Running 2 tests 22:35:49 INFO - TEST-START | dromaeo_css 22:35:49 INFO - operating with platform_type : linux_ 22:35:49 INFO - Initialising browser for dromaeo_css test... 22:35:49 INFO - TEST-INFO | started process 8847 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYn23Dl/profile http://localhost:51253/getInfo.html) 22:35:53 INFO - PROCESS | 8847 | __metrics Screen width/height:1600/1200 22:35:53 INFO - PROCESS | 8847 | colorDepth:24 22:35:53 INFO - PROCESS | 8847 | Browser inner width/height: 1024/697 22:35:53 INFO - PROCESS | 8847 | __metrics 22:35:54 INFO - PROCESS | 8847 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:35:54 INFO - PROCESS | 8847 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:35:55 INFO - TEST-INFO | 8847: exit 0 22:35:55 INFO - Browser initialized. 22:35:55 INFO - Running cycle 1/1 for dromaeo_css test... 22:35:55 INFO - TEST-INFO | started process 8932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYn23Dl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:07 INFO - PROCESS | 8932 | RSS: Main: 145211392 22:36:07 INFO - PROCESS | 8932 | 22:36:58 INFO - PROCESS | 8932 | 1472794618438 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] 22:39:07 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51253/tests/dromaeo/cssquery-ext.html) 22:39:08 INFO - PROCESS | 8932 | RSS: Main: 225263616 22:39:08 INFO - PROCESS | 8932 | 22:42:11 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-ext.html (next: http://localhost:51253/tests/dromaeo/cssquery-jquery.html) 22:42:11 INFO - PROCESS | 8932 | RSS: Main: 235192320 22:42:11 INFO - PROCESS | 8932 | 22:45:13 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51253/tests/dromaeo/cssquery-mootools.html) 22:45:14 INFO - PROCESS | 8932 | RSS: Main: 216875008 22:45:14 INFO - PROCESS | 8932 | 22:48:16 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51253/tests/dromaeo/cssquery-prototype.html) 22:48:16 INFO - PROCESS | 8932 | RSS: Main: 174600192 22:48:16 INFO - PROCESS | 8932 | 22:51:19 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51253/tests/dromaeo/cssquery-yui.html) 22:51:19 INFO - PROCESS | 8932 | RSS: Main: 179478528 22:51:19 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-yui.html (next: http://localhost:51253/tests/dromaeo/cssquery-dojo.html) 22:54:22 INFO - PROCESS | 8932 | RSS: Main: 262365184 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | __start_tp_report 22:54:22 INFO - PROCESS | 8932 | _x_x_mozilla_page_load 22:54:22 INFO - PROCESS | 8932 | _x_x_mozilla_page_load_details 22:54:22 INFO - PROCESS | 8932 | |i|pagename|runs| 22:54:22 INFO - PROCESS | 8932 | |0;dojo.html;3205.599300087489;3210.743140548755;3221.4040402525543;3234.4339740545397;3238.805671659702;5564.86087847804;5650.350209725882;5658.547316214704;5669.262995810549;5676.006698827704;5563.805266815662;5618.5786066045;5627.071533197028;5640.492355687988;5643.661380317184;5044.39467263927;5051.292819005343;5085.79656813727;5101.923471147936;5158.226266060084;5083.262926875601;5121.795128194868;5148.227765835136;5169.147090730025;5169.379674439054;5060.51925067118;5119.411267704206;5141.614378921578;5158.458361872005;5175.948240517584;5198.324217851655;5204;5214.478552144793;5216.686998780086;5258.605604579634;4980.078685443204;5038.899222015539;5102.6173037022;5133.6919784813035;5138.152204886189;5537.58468114893;5582.190582365534;5595.720213989325;5602.719864006784;5629.268195134607;6082.074122787376;6091.547368473436;6106.413827121106;6110.327863934964;6116.357782432825;5888.440598143169;5971.402859714038;6006.699665016732;6033.909491357633;6111.455355893571;5648.406917273709;5661.065924122515;5673.26247587815;5678.829635110953;5680.488756011979;6211.16149319845;6215.9378406216265;6229.96885015572;6237.251529816361;6242.937570624326;5757.625754325955;5831.416858314219;5891.3224979127945;5897.085951677497;5978.671173085435;5950.9107363389585;5955.940440595626;5972.104184372379;5982.611130276518;5997.850053748692;5895.823125306248;5916.083007133901;5923.496502797224;5951.494122999594;5968.791092311835;5786.42135786418;5839.9124013139835;5878.676672783038;5937.70311484424;5949.434803693643;4562.749048802348;4602.861914142581;4603.58567728906;4603.746793926366;4613.40025796651;4010.859619913289;4048.6963477739287;4125.339945608748;4133.6693064554765;4182.2890108681595;5724.914126288109;5730.799422020293;5733.741981610838;5748.425157484218;5788.623739456922;6047.2743270806905;6081.939180608226;6095;6099.146119543268;6114.541409394314;5556.72216389187;5675.46083122111;5705.716213851933;5713.200151978726;5714.085746280658;5266.8419947401635;5277.736113194325;5281.102212623863;5297.073012222845;5307.389650190277;6301.653409062454;6364.681765911686;6369.235691716931;6372.808815735535;6386.074019267273;5910.231669882888;5988.850278743066;6022.698865056817;6113;6117.96941015292;6226.034964580497;6230.626162430269;6284.968575157095;6289.056641503811;6303.432691057827;5908.202392676933;5927.8221653350465;5930.11048342752;5931.258592675916;5957.463828255478;5819.79630712923;5906.26171728534;6015.789447369321;6031.155638210742;6048.818535443944;5754.136879467946;5877.882442351214;5880.235569375954;5907.70461476933;5910.52715782745;5806.390329015392;5861.238039054895;5862.062070068769;5867.647941123546;5870.412958704095;5780.3930587289415;5829.15477255804;5841.4450627190345;5845.918505076575;5850.473457388855;5887;5916.875793599374;5919.704014799329;5980.970095149496;6012.699365031818;5775.48020678141;5802.0426629605645;5813.3895940925595;5814;5816.70916454184 22:54:22 INFO - PROCESS | 8932 | |1;ext.html;592.5526227698087;594.1147689941986;624.2696045626616;627.6955676496901;628.9937100628993;34506.48240276395;44773.552264477345;45467.31799023017;45577.54422455774;45622;48452.75773621127;48707.51292487074;48708;48763.75618121905;48866.26700599494;8455;9122.407043542165;9264.953675231649;9298.16316531512;10882.782344353109;11062.446877656124;11207.271527350696;11209.103271738242;11221;11221.943890280538;11229.32624042556;11287.435628218598;11300.830487542693;11311.208215424924;11322.773544529104;11159.609413670523;11183.161262905314;11196.888031119666;11208.887911120948;11215.60745373908;11054.778904421946;11124.777504449947;11133.443327833576;11142.554297828157;11147.498362573704;45585.54414455846;45849.54150458519;45887;45931;45956.77021614904;3737.383331750258;3751.531058617673;3786.4320351947154;3812.6759225465867;3842.5965273646425;35671.82164089189;35859.28281434383;35943;36238.27523449543;37516.249675006344;4236.301010333292;4385.912281754379;4400.977995110036;4440.755758433284;4487.057717879249;355044;355899.99999999744;356027;356206;358441;37523.4371484428;38736.6126338733;38803;62580.68709656514;62918.68540657267;41419.37870931938;41494;41530.1693966125;41533;41579.168416631495;16830.242639081273;16937.491875243762;16949.237284322237;16979.660406791794;16981.150942452827;11539.249948753304;11732.70668233301;11796.88203117975;12027.09796765246;14986.550403487912;9026.909730902607;9372.484513351696;9398.295127865438;9455.479948602893;9562.760930976781;3491;3561.661642143992;3591.7069854852234;3600.513930619385;3627.093226693327;1051.0562767549368;1051.2378525568902;1063.6808957312776;1139.8746137924823;1153.2792004996877;34995.475067874;36298;36391.63608363883;36417.45373819395;36440.453393199125;1187.1511869013696;1198.101423932051;1198.9580364687195;1201.303244118409;1239.4856134703996;18525.42533884608;18655.90672046631;18807.717884231748;18878.81121188798;18908.621827563646;698953;707346;708693;709784;711062;65737.67131164313;66222.33777662161;66438;66439.66780166069;66804.6659766698;1323.973520529584;1406.324964017257;1436.3249272841747;1450.5285782120852;1455.396010655566;35139.82430087833;36602.03432930701;36802.447963280036;36819.89540313795;37012;55451.16823247735;56120.15819762625;56171.43828561662;56172;56284.718576406856;433906;436248;436652;438057;438267;31312;49301.26048109353;49858.50141498539;50559.49440505695;54760;50348.748256258485;55341.72329138489;55648.72175639096;56315.43684563102;62208.68895655493;47077.76461117809;48742.75628621834;48887;49591.256131158785;50278;48192.759036204596;48861.75569122132;49163;49252;49578.75210623924;46773.29840052334;47566.76216618895;47845.28232076452;47944.520554794006;48062;38138.80930595329;40601.79699101485;42184.156316872875;42191.36712949247;42732.35901461543;30993.380132397677;31244.218894527818;31358.68641313649;31476.37047259088;31924.8403757989 22:54:22 INFO - PROCESS | 8932 | |2;jquery.html;393.66735108833024;415.5553557693269;421.58052737526157;423.6780047164155;439.6043560795284;15329.616759581002;20217;20463;20500.38498845035;21544.676829847565;19945.900270498627;20178;20236.29172978946;20317.79682203181;20551.177952881902;5605.131204663284;5721.971390143054;5860.912086318687;5867.941320586803;5907.172995780573;5846.619969701956;5852.882942341151;5873.1190321451695;5874.500667443251;5875.618084824473;5743.684097374643;5983.970080149593;6024.939750602482;6091.269047714259;6091.573589848713;6014.909776353358;6036.486898613623;6062.363451837559;6222.5644204905675;6569.605823650549;5903.822885313448;5913.142594323842;5915.497182739472;5919.260092488439;5927.851803704899;14795.334209960578;23613.173538926054;23738.893164486064;24394.634080488806;25966;2427.080479238808;2464.0267094497754;2595.4679290745353;2639.90443965754;2836.177508522526;10684.679459616145;10831.891681083245;11040.668779936615;11154.274972126786;11192.048675862561;1872.8314451699275;1879.915403806832;1978.6834106542883;1988.3537850198743;2032.5528383755698;60063;65608;66578.33421665769;69448.30551694469;79672;10698.78602427947;10997.725056873482;11024.944875275733;11484.310941343547;11682.182247242781;16166.27251773673;16182.59543511398;16203.918980405258;16227.026378417097;16293.429729959631;8161.5919204039565;8307.460015098999;8387.370947178986;8408.864803251605;8502.787430314167;8401.411901166859;8921.776955576033;8932.821343573092;8934.955325223333;9167.816643667089;4844.709317440965;4979.481258216279;5008.973160502088;5035.823746168867;5100.209467532464;1837.8281301445911;1949.311904868793;2046.2122082998153;2071.2129390831387;2113.9154433822473;942.6606421688186;1034.715453250362;1038.5222797513077;1042.4735508568276;1072.099436473366;20222.898885505772;20265.79734202639;20394.69407958882;20552;20707.896460517903;1029.7631544744602;1054.9683509494728;1064.7018834726289;1075.1452595186845;1088.450332582041;10775.946120269506;11812.173147879737;11864.347460889561;11926.522939082339;12001.699957501132;532042;540608;544375;555639;558840;90587;92550.53724731333;99858.00141998632;101088.49455752675;105846;1077.272840832435;1094.698957786599;1121.85415895933;1124.3591153042687;1140.378493720924;18972.241110355983;19115;19147.90426047861;19321.51696207606;19633.31283405074;32356.191095222806;37061;44801;45249.54750452585;46045;63778.68110659417;64799.67600161969;65272.347276528526;66661.33338666745;66690.33309667035;7799.9220007799195;8952.68665596701;8971.596278167628;9163.633454661738;9329.253659707343;12197.634070977885;13815.240161791198;14046.367913443715;14520.782188267396;14727.55817325482;7492.700291988477;11524.481398337262;11628.592999244986;12307.076969227164;12440.129190956546;11516;11779.937853557294;12042.63872083839;12226.388680566113;12463.252204867737;12202.816957745463;12332.691682708004;12456.813147802972;12668.303243321705;12671.80992285098;9022.954885225532;9277;9355.906440935503;9475.668351607661;9518.23854091657;11769.941150294193;12135.635930922086;12211.206271592318;12427.508698956182;12428.56500022495 22:54:22 INFO - PROCESS | 8932 | |3;mootools.html;1534.0718865086626;1587.3650539784085;1772.6277481728837;1821.1349608935766;1893.6780747272953;8473.406861519688;9026.72919812405;9035.864462033058;9036.95481522593;9040.954795226024;8157.347412207024;8845.823083538326;8852.114788521145;9086.13681700239;9105.544722763869;2426.4783071639595;2451.82837201396;2452.6443665668467;2453.6196889482158;2457.803375729937;2316.721993360799;2322.0479603362623;2359.917402890899;2361.8819059047064;2363.865344634568;2424.35754525051;2441.633754936756;2447.4003869052017;2449.963250551234;2452.8160387970975;2254.8196144308417;2260.3106052653934;2264.784845439681;2265.2411442166813;2303.435658263736;2315.79157875792;2339.2865176121277;2361.067378385528;2361.8819059046978;2365.47959448921;9124.45253284805;9212.585433655502;9229;9233.353665243436;9245.167934885892;5361;5430.239766432703;5499.340079190482;5503;5505.366882808448;3560.2523470071314;3563.8396272167815;3575.302815950891;3610.981945090284;3628.328759179534;1204.656672848234;1212.078820096724;1216.5924415320878;1225.2525959164902;1235.4440501774238;13237.536686216032;13310.068295219338;13312;13324.467021319233;13337.73324533504;4780.091782561302;4818.807247710122;4841.12859685253;4943.456219815817;4967.776450059756;11046.226764126433;11093.389863557582;11119.72200694989;11123.55505779776;11146.665600032013;5609.046462101453;5617.747201375866;5620.409856965041;5621.4378562144275;5630.887382252332;4165.125323682;4326.6754993375625;4428.955710442919;5044.646874718808;5133.563647089964;4037.2934736421007;4063.146739184804;4124.628783409507;4129.194807012604;4130.938035929464;1164.6389619218069;1174.7650469906052;1187.121530067761;1208.2629595946466;1209.8366720492797;786.5909726942073;840.1472505407016;844.7043534762906;915.8855143107112;992.5087081894509;6228.813135605939;6293.811185664437;6393.360663933569;6457.321981192001;6638.17022872141;977.032737589781;985.4776968206719;995.6963126246565;1006.2201793609864;1039.6932904793105;4147.440095587118;4362.563743625676;4381.9342709859375;4437.689361744647;4439.356293337448;11322.377269250106;11861.703457413461;11877.821832672516;12101.576444824388;12109.576164834365;11909.047276217882;11935.641930741918;11990.100742444352;12018;12049.457774400173;8652.394332396672;8677;8750.431221970552;8870.467771933705;8910.821783564292;6261.217347831521;6438.7746428876635;6450.354964503512;6483.513736469878;6632.801015969529;10777.137828973506;10929.125669946226;10932.289401188897;10932.945335273273;10936.781264374828;10590.576376945144;10772.569097236023;10777.515011824644;11146.832797507881;11162.16283778736;8396.37027222973;8436.746897593082;8458.873116903374;8583.742487725129;8599;8718.782030449036;8869.379143459895;8873.157050080232;8874.378793484653;8879.733607991771;7348.816279593053;7441.739539116105;7470.439717021137;7503.849923001619;7629.198934111839;7288.416926645961;7486.513376630502;7936.127026027017;8041.356691464552;8331.416800823885;7556.319931206218;7588.354989825927;7603.771886843404;7683.961580192287;7690.846373044088;5677.971610142088;5712.914306285492;5722;5799;5871.442212989759;5569.721513924206;5643.322801263875;5762.827115186551;5803.47768700819;5825.79609713658 22:54:22 INFO - PROCESS | 8932 | |4;prototype.html;352.45369677000645;357.4227622389841;360.7691077710266;363.7944561322854;365.6855104610035;5593.664380137189;5598.608097433171;5656.783791484825;5666.291713535808;5675.6878371689545;5488.4237155098635;5525.778968841241;5548.805791797288;5554.91667624986;5557.666540007603;4226.661867050644;4335.6748243881675;4366.716163449381;4370.868873933787;4395.538468460813;4345.3047512398025;4346.543612920629;4353.129374125172;4377.365282034103;4408.4709834819705;4303.289957157065;4330.112326972963;4372.191144638252;4374.212641724488;4385.407969924051;4105.692073094515;4294.892627684333;4315.697901146921;4355.695101342907;4374.934375984363;3807.7524960877454;3813.31360355136;3826.368649172883;3826.7703937763545;3833.6358045985585;5394.031178619775;5400.487863398254;5405.702686352249;5407.567394608422;5409.83770486886;4814.181589129857;4846.224604063333;4881;4905.9264111038365;4994.500549944976;5675.574331925122;5702.54379649627;5713.885722285531;5714.914276285859;5743.339515955635;4212.157568486315;4212.9157416851795;4218.303979843353;4226.344916537942;4229.667654688763;5644.238027866227;5739.995500787304;5788.5369170466265;5994.790182343597;6047.546434017467;4240.787960601958;4245.745255284693;4258.190943720683;4292.59220374064;4321.783910804447;4361.454818147731;4362.192994296065;4367.366731823868;4380.780960952004;4381.539938306496;4608.9308660370125;4742.668013239109;4901.36282283308;4932.605391568666;4969.155243608595;5783.884322313615;5816.447437493432;5828.504577110908;5833.766649334064;5857.531397488191;1486.420296084528;1510.7431736604804;1515.6589767302269;1517.7419838627459;1518.9696206075816;967.4437198610827;988.1699372527112;997.8952210017843;1028.681108856242;1053.4311471805293;5561.6663000220115;5577.972110139423;5590.413006634326;5604.943950560442;5614.494695477427;5850.795222167289;5893.204417403681;5897.439743224387;5935.139404786281;5945.732442040118;5412.431694672082;5614;5719.513841323451;5751.971240143856;5759.798407055817;3181.2683082891062;3199.6561444193494;3200.5999250093737;3213.903582892517;3227;6075.756969721162;6109.877802443926;6147.538934579924;6275.748970041239;6282.623042617458;6069.514438844882;6091.573589848668;6099.542534309944;6114.602550834182;6119.173911521976;6173.32093469727;6179.011358182669;6189.535784816067;6191.876162476815;6199.814005579839;5675.687837168995;5978.850528736816;5985.910211346746;5992.610480318765;5994.730237139426;5886.939571150235;5897.705114744332;5908.9068522323505;5910.674912879833;5979.5814292999075;5977.551683623833;6122.4795892347865;6182.690865456799;6210.627362358273;6225.8132256032395;4274.444322238089;4289.378040184219;4326.675499337625;4352.760598167131;4353.194658988097;6089.716356366363;6129.785457508966;6136.14669178834;6154.292256390572;6154.476869466145;5747.3103227612955;5911.7635294587735;5916.73374698131;5920.230370051866;5925.703714814328;5799.652020878761;5838.824835254949;5861.736221870111;5922.437368449991;5922.881542369042;5705.885882282414;5749.942500575108;5770.653760774367;5815.505682017076;5840.707964601838;5833.037548804569;5864.44287792659;5876.912771137353;5878.118282257696;5889.882202356014;5724.740557077436;5744.712764361849;5753.798617048383;5767.5385969121535;5771.942280577308 22:54:22 INFO - PROCESS | 8932 | |5;yui.html;762.744480598999;791.616066207889;800.7437619961613;846.1073567386405;875.4178471316575;16331.591710207236;16925.99999999997;16978.066206358646;17077.316907323708;17250.223739931702;16139.919300403482;16243.83756162438;16252.674946501063;16296.592585185377;16313.184340782973;721.1598487761764;849.524266410812;874.5802015032788;875.7460336502438;890.0209769253802;899.415380003;899.7840518275632;900.7883147460342;901.2699713232303;902.463034494478;943.0333907744582;943.448082871521;945.399671208784;946.5314669238702;947.6920000999668;762.5653377574785;901.4185850126664;924.4591913730442;926.1433174313801;930.33481061041;685.7702669605688;686.6429456682547;688.683205725367;688.7141836137997;690.1304356510782;16154.596135096599;16159.353625854952;16188;16194.83805161945;16231.675366492722;15828.891977561576;16075.276612552463;16274.75587866183;16437.506874793777;16567.585810354718;14661.780073298909;14837;14906.552803415914;14930.47743328989;14964.700705985819;4821.132196204681;4895.583875370562;4916.385451818523;4980.477049909779;5122.538971492546;84823.57588212081;90809.54595226982;91829.54085229598;92023.53988230016;92894.5355273226;12725.809112863315;12757.936210319074;12890.806637900252;13046.54337098197;13346.666333341744;25056.749432505443;25171.87414062918;25231.495370092864;25269;25299.62050569243;8834.823303533893;8903.465792052497;8973.192412682889;9086.954565227132;9091.227245684191;5152.458991805881;5180.844574662766;5228.738563071831;5230.764615592308;5345.919811202835;3657.762245454037;3784.299904517673;3804.410316400962;3810.0665336992615;3810.8856734298015;572.8567858035491;583.5419195931258;588.5115354255958;593.4836692077834;596.4095545410099;672.4653900149393;723.5116296499947;738.4240292571802;745.0612228592013;750.9399248060142;9243.630254789867;10024.79950400988;10197.847032294521;10261.58953641846;10578.629747958785;632.6513437653128;654.0352979355508;661.7915356662636;754.479409207645;768.769369189241;4687.882802929886;4909.4845041270155;4938.358013458299;5032.270320803463;5039.647224694236;73198.63400682963;73834.63082684552;74548;74621;75571;106551.46724266329;108818;112760;114137;114410;78810.6059469699;79351.60324198342;88518;98928;104361;10396.399446375268;10664.253502254767;10779.892201078203;11118.61084862026;11214.215004949901;41679;44559.55440445554;47417;48565.27152092792;53038.46961530489;75803;77216;79732.60133699294;80134;81789;13177.275249860966;13416.932915335361;13865.792013120014;13977.301134943009;14586.562403127924;16026.67946641084;16137.19314034317;16666.16669166561;16778.077205753314;16800.57998550046;14387.712245755236;15240.923795380952;16092.67814643724;17005.149742512578;17118.058506782247;14012.43950241979;14136.929315353358;14318.212498312692;14930;15500;14745.336459859547;14915.254237288309;15712.292946817191;15805.52583422499;16759.329626815277;11809.940950295537;12128.75742485163;12330;12424.937875310565;12874.420651070912;12737.363131843556;12990.740185196431;13181.538646147339;13232.404541795835;13295 22:54:22 INFO - PROCESS | 8932 | __end_tp_report 22:54:22 INFO - PROCESS | 8932 | __start_cc_report 22:54:22 INFO - PROCESS | 8932 | _x_x_mozilla_cycle_collect,1103 22:54:22 INFO - PROCESS | 8932 | __end_cc_report 22:54:22 INFO - PROCESS | 8932 | __startTimestamp1472795662942__endTimestamp 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | ------- Summary: start ------- 22:54:22 INFO - PROCESS | 8932 | Number of tests: 6 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#0] dojo.html Cycles:165 Average:5597.56 Median:5813.39 stddev:640.41 (11.0%) stddev-sans-first:614.27 22:54:22 INFO - PROCESS | 8932 | Values: 3205.6 3210.7 3221.4 3234.4 3238.8 5564.9 5650.4 5658.5 5669.3 5676.0 5563.8 5618.6 5627.1 5640.5 5643.7 5044.4 5051.3 5085.8 5101.9 5158.2 5083.3 5121.8 5148.2 5169.1 5169.4 5060.5 5119.4 5141.6 5158.5 5175.9 5198.3 5204.0 5214.5 5216.7 5258.6 4980.1 5038.9 5102.6 5133.7 5138.2 5537.6 5582.2 5595.7 5602.7 5629.3 6082.1 6091.5 6106.4 6110.3 6116.4 5888.4 5971.4 6006.7 6033.9 6111.5 5648.4 5661.1 5673.3 5678.8 5680.5 6211.2 6215.9 6230.0 6237.3 6242.9 5757.6 5831.4 5891.3 5897.1 5978.7 5950.9 5955.9 5972.1 5982.6 5997.9 5895.8 5916.1 5923.5 5951.5 5968.8 5786.4 5839.9 5878.7 5937.7 5949.4 4562.7 4602.9 4603.6 4603.7 4613.4 4010.9 4048.7 4125.3 4133.7 4182.3 5724.9 5730.8 5733.7 5748.4 5788.6 6047.3 6081.9 6095.0 6099.1 6114.5 5556.7 5675.5 5705.7 5713.2 5714.1 5266.8 5277.7 5281.1 5297.1 5307.4 6301.7 6364.7 6369.2 6372.8 6386.1 5910.2 5988.9 6022.7 6113.0 6118.0 6226.0 6230.6 6285.0 6289.1 6303.4 5908.2 5927.8 5930.1 5931.3 5957.5 5819.8 5906.3 6015.8 6031.2 6048.8 5754.1 5877.9 5880.2 5907.7 5910.5 5806.4 5861.2 5862.1 5867.6 5870.4 5780.4 5829.2 5841.4 5845.9 5850.5 5887.0 5916.9 5919.7 5981.0 6012.7 5775.5 5802.0 5813.4 5814.0 5816.7 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#1] ext.html Cycles:180 Average:66879.78 Median:38769.81 stddev:139375.23 (359.5%) stddev-sans-first:139677.36 22:54:22 INFO - PROCESS | 8932 | Values: 592.6 594.1 624.3 627.7 629.0 34506.5 44773.6 45467.3 45577.5 45622.0 48452.8 48707.5 48708.0 48763.8 48866.3 8455.0 9122.4 9265.0 9298.2 10882.8 11062.4 11207.3 11209.1 11221.0 11221.9 11229.3 11287.4 11300.8 11311.2 11322.8 11159.6 11183.2 11196.9 11208.9 11215.6 11054.8 11124.8 11133.4 11142.6 11147.5 45585.5 45849.5 45887.0 45931.0 45956.8 3737.4 3751.5 3786.4 3812.7 3842.6 35671.8 35859.3 35943.0 36238.3 37516.2 4236.3 4385.9 4401.0 4440.8 4487.1 355044.0 355900.0 356027.0 356206.0 358441.0 37523.4 38736.6 38803.0 62580.7 62918.7 41419.4 41494.0 41530.2 41533.0 41579.2 16830.2 16937.5 16949.2 16979.7 16981.2 11539.2 11732.7 11796.9 12027.1 14986.6 9026.9 9372.5 9398.3 9455.5 9562.8 3491.0 3561.7 3591.7 3600.5 3627.1 1051.1 1051.2 1063.7 1139.9 1153.3 34995.5 36298.0 36391.6 36417.5 36440.5 1187.2 1198.1 1199.0 1201.3 1239.5 18525.4 18655.9 18807.7 18878.8 18908.6 698953.0 707346.0 708693.0 709784.0 711062.0 65737.7 66222.3 66438.0 66439.7 66804.7 1324.0 1406.3 1436.3 1450.5 1455.4 35139.8 36602.0 36802.4 36819.9 37012.0 55451.2 56120.2 56171.4 56172.0 56284.7 433906.0 436248.0 436652.0 438057.0 438267.0 31312.0 49301.3 49858.5 50559.5 54760.0 50348.7 55341.7 55648.7 56315.4 62208.7 47077.8 48742.8 48887.0 49591.3 50278.0 48192.8 48861.8 49163.0 49252.0 49578.8 46773.3 47566.8 47845.3 47944.5 48062.0 38138.8 40601.8 42184.2 42191.4 42732.4 30993.4 31244.2 31358.7 31476.4 31924.8 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#2] jquery.html Cycles:180 Average:30919.02 Median:22578.93 stddev:89773.21 (397.6%) stddev-sans-first:89995.79 22:54:22 INFO - PROCESS | 8932 | Values: 393.7 415.6 421.6 423.7 439.6 15329.6 20217.0 20463.0 20500.4 21544.7 19945.9 20178.0 20236.3 20317.8 20551.2 5605.1 5722.0 5860.9 5867.9 5907.2 5846.6 5852.9 5873.1 5874.5 5875.6 5743.7 5984.0 6024.9 6091.3 6091.6 6014.9 6036.5 6062.4 6222.6 6569.6 5903.8 5913.1 5915.5 5919.3 5927.9 14795.3 23613.2 23738.9 24394.6 25966.0 2427.1 2464.0 2595.5 2639.9 2836.2 10684.7 10831.9 11040.7 11154.3 11192.0 1872.8 1879.9 1978.7 1988.4 2032.6 60063.0 65608.0 66578.3 69448.3 79672.0 10698.8 10997.7 11024.9 11484.3 11682.2 16166.3 16182.6 16203.9 16227.0 16293.4 8161.6 8307.5 8387.4 8408.9 8502.8 8401.4 8921.8 8932.8 8935.0 9167.8 4844.7 4979.5 5009.0 5035.8 5100.2 1837.8 1949.3 2046.2 2071.2 2113.9 942.7 1034.7 1038.5 1042.5 1072.1 20222.9 20265.8 20394.7 20552.0 20707.9 1029.8 1055.0 1064.7 1075.1 1088.5 10775.9 11812.2 11864.3 11926.5 12001.7 532042.0 540608.0 544375.0 555639.0 558840.0 90587.0 92550.5 99858.0 101088.5 105846.0 1077.3 1094.7 1121.9 1124.4 1140.4 18972.2 19115.0 19147.9 19321.5 19633.3 32356.2 37061.0 44801.0 45249.5 46045.0 63778.7 64799.7 65272.3 66661.3 66690.3 7799.9 8952.7 8971.6 9163.6 9329.3 12197.6 13815.2 14046.4 14520.8 14727.6 7492.7 11524.5 11628.6 12307.1 12440.1 11516.0 11779.9 12042.6 12226.4 12463.3 12202.8 12332.7 12456.8 12668.3 12671.8 9023.0 9277.0 9355.9 9475.7 9518.2 11769.9 12135.6 12211.2 12427.5 12428.6 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#3] mootools.html Cycles:180 Average:6133.65 Median:4799.45 stddev:3553.48 (74.0%) stddev-sans-first:3546.64 22:54:22 INFO - PROCESS | 8932 | Values: 1534.1 1587.4 1772.6 1821.1 1893.7 8473.4 9026.7 9035.9 9037.0 9041.0 8157.3 8845.8 8852.1 9086.1 9105.5 2426.5 2451.8 2452.6 2453.6 2457.8 2316.7 2322.0 2359.9 2361.9 2363.9 2424.4 2441.6 2447.4 2450.0 2452.8 2254.8 2260.3 2264.8 2265.2 2303.4 2315.8 2339.3 2361.1 2361.9 2365.5 9124.5 9212.6 9229.0 9233.4 9245.2 5361.0 5430.2 5499.3 5503.0 5505.4 3560.3 3563.8 3575.3 3611.0 3628.3 1204.7 1212.1 1216.6 1225.3 1235.4 13237.5 13310.1 13312.0 13324.5 13337.7 4780.1 4818.8 4841.1 4943.5 4967.8 11046.2 11093.4 11119.7 11123.6 11146.7 5609.0 5617.7 5620.4 5621.4 5630.9 4165.1 4326.7 4429.0 5044.6 5133.6 4037.3 4063.1 4124.6 4129.2 4130.9 1164.6 1174.8 1187.1 1208.3 1209.8 786.6 840.1 844.7 915.9 992.5 6228.8 6293.8 6393.4 6457.3 6638.2 977.0 985.5 995.7 1006.2 1039.7 4147.4 4362.6 4381.9 4437.7 4439.4 11322.4 11861.7 11877.8 12101.6 12109.6 11909.0 11935.6 11990.1 12018.0 12049.5 8652.4 8677.0 8750.4 8870.5 8910.8 6261.2 6438.8 6450.4 6483.5 6632.8 10777.1 10929.1 10932.3 10932.9 10936.8 10590.6 10772.6 10777.5 11146.8 11162.2 8396.4 8436.7 8458.9 8583.7 8599.0 8718.8 8869.4 8873.2 8874.4 8879.7 7348.8 7441.7 7470.4 7503.8 7629.2 7288.4 7486.5 7936.1 8041.4 8331.4 7556.3 7588.4 7603.8 7684.0 7690.8 5678.0 5712.9 5722.0 5799.0 5871.4 5569.7 5643.3 5762.8 5803.5 5825.8 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#4] prototype.html Cycles:180 Average:4916.27 Median:5629.37 stddev:1438.09 (25.5%) stddev-sans-first:1400.73 22:54:22 INFO - PROCESS | 8932 | Values: 352.5 357.4 360.8 363.8 365.7 5593.7 5598.6 5656.8 5666.3 5675.7 5488.4 5525.8 5548.8 5554.9 5557.7 4226.7 4335.7 4366.7 4370.9 4395.5 4345.3 4346.5 4353.1 4377.4 4408.5 4303.3 4330.1 4372.2 4374.2 4385.4 4105.7 4294.9 4315.7 4355.7 4374.9 3807.8 3813.3 3826.4 3826.8 3833.6 5394.0 5400.5 5405.7 5407.6 5409.8 4814.2 4846.2 4881.0 4905.9 4994.5 5675.6 5702.5 5713.9 5714.9 5743.3 4212.2 4212.9 4218.3 4226.3 4229.7 5644.2 5740.0 5788.5 5994.8 6047.5 4240.8 4245.7 4258.2 4292.6 4321.8 4361.5 4362.2 4367.4 4380.8 4381.5 4608.9 4742.7 4901.4 4932.6 4969.2 5783.9 5816.4 5828.5 5833.8 5857.5 1486.4 1510.7 1515.7 1517.7 1519.0 967.4 988.2 997.9 1028.7 1053.4 5561.7 5578.0 5590.4 5604.9 5614.5 5850.8 5893.2 5897.4 5935.1 5945.7 5412.4 5614.0 5719.5 5752.0 5759.8 3181.3 3199.7 3200.6 3213.9 3227.0 6075.8 6109.9 6147.5 6275.7 6282.6 6069.5 6091.6 6099.5 6114.6 6119.2 6173.3 6179.0 6189.5 6191.9 6199.8 5675.7 5978.9 5985.9 5992.6 5994.7 5886.9 5897.7 5908.9 5910.7 5979.6 5977.6 6122.5 6182.7 6210.6 6225.8 4274.4 4289.4 4326.7 4352.8 4353.2 6089.7 6129.8 6136.1 6154.3 6154.5 5747.3 5911.8 5916.7 5920.2 5925.7 5799.7 5838.8 5861.7 5922.4 5922.9 5705.9 5749.9 5770.7 5815.5 5840.7 5833.0 5864.4 5876.9 5878.1 5889.9 5724.7 5744.7 5753.8 5767.5 5771.9 22:54:22 INFO - PROCESS | 8932 | 22:54:22 INFO - PROCESS | 8932 | [#5] yui.html Cycles:180 Average:21344.60 Median:43119.28 stddev:29135.19 (67.6%) stddev-sans-first:29175.93 22:54:22 INFO - PROCESS | 8932 | Values: 762.7 791.6 800.7 846.1 875.4 16331.6 16926.0 16978.1 17077.3 17250.2 16139.9 16243.8 16252.7 16296.6 16313.2 721.2 849.5 874.6 875.7 890.0 899.4 899.8 900.8 901.3 902.5 943.0 943.4 945.4 946.5 947.7 762.6 901.4 924.5 926.1 930.3 685.8 686.6 688.7 688.7 690.1 16154.6 16159.4 16188.0 16194.8 16231.7 15828.9 16075.3 16274.8 16437.5 16567.6 14661.8 14837.0 14906.6 14930.5 14964.7 4821.1 4895.6 4916.4 4980.5 5122.5 84823.6 90809.5 91829.5 92023.5 92894.5 12725.8 12757.9 12890.8 13046.5 13346.7 25056.7 25171.9 25231.5 25269.0 25299.6 8834.8 8903.5 8973.2 9087.0 9091.2 5152.5 5180.8 5228.7 5230.8 5345.9 3657.8 3784.3 3804.4 3810.1 3810.9 572.9 583.5 588.5 593.5 596.4 672.5 723.5 738.4 745.1 750.9 9243.6 10024.8 10197.8 10261.6 10578.6 632.7 654.0 661.8 754.5 768.8 4687.9 4909.5 4938.4 5032.3 5039.6 73198.6 73834.6 74548.0 74621.0 75571.0 106551.5 108818.0 112760.0 114137.0 114410.0 78810.6 79351.6 88518.0 98928.0 104361.0 10396.4 10664.3 10779.9 11118.6 11214.2 41679.0 44559.6 47417.0 48565.3 53038.5 75803.0 77216.0 79732.6 80134.0 81789.0 13177.3 13416.9 13865.8 13977.3 14586.6 16026.7 16137.2 16666.2 16778.1 16800.6 14387.7 15240.9 16092.7 17005.1 17118.1 14012.4 14136.9 14318.2 14930.0 15500.0 14745.3 14915.3 15712.3 15805.5 16759.3 11809.9 12128.8 12330.0 12424.9 12874.4 12737.4 12990.7 13181.5 13232.4 13295.0 22:54:22 INFO - PROCESS | 8932 | -------- Summary: end -------- 22:54:22 INFO - PROCESS | 8932 | 22:54:23 INFO - TEST-INFO | 8932: exit 0 22:54:23 INFO - TEST-OK | dromaeo_css | took 1114058ms 22:54:23 INFO - TEST-START | kraken 22:54:23 INFO - operating with platform_type : linux_ 22:54:23 INFO - Initialising browser for kraken test... 22:54:23 INFO - TEST-INFO | started process 9133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0MjSIk/profile http://localhost:51253/getInfo.html) 22:54:27 INFO - PROCESS | 9133 | __metrics Screen width/height:1600/1200 22:54:27 INFO - PROCESS | 9133 | colorDepth:24 22:54:27 INFO - PROCESS | 9133 | Browser inner width/height: 1024/697 22:54:27 INFO - PROCESS | 9133 | __metrics 22:54:28 INFO - PROCESS | 9133 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:54:28 INFO - PROCESS | 9133 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:54:29 INFO - TEST-INFO | 9133: exit 0 22:54:29 INFO - Browser initialized. 22:54:29 INFO - Running cycle 1/1 for kraken test... 22:54:29 INFO - TEST-INFO | started process 9223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0MjSIk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:41 INFO - PROCESS | 9223 | RSS: Main: 145268736 22:54:41 INFO - PROCESS | 9223 | 22:54:43 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-beat-detection) 22:54:44 INFO - PROCESS | 9223 | RSS: Main: 191418368 22:54:44 INFO - PROCESS | 9223 | 22:54:47 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-dft) 22:54:47 INFO - PROCESS | 9223 | RSS: Main: 182161408 22:54:47 INFO - PROCESS | 9223 | 22:54:50 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-fft) 22:54:51 INFO - PROCESS | 9223 | RSS: Main: 271409152 22:54:51 INFO - PROCESS | 9223 | 22:54:53 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-oscillator) 22:54:54 INFO - PROCESS | 9223 | RSS: Main: 175730688 22:54:54 INFO - PROCESS | 9223 | 22:54:56 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:54:56 INFO - PROCESS | 9223 | RSS: Main: 177971200 22:54:56 INFO - PROCESS | 9223 | 22:54:59 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-darkroom) 22:55:00 INFO - PROCESS | 9223 | RSS: Main: 214077440 22:55:00 INFO - PROCESS | 9223 | 22:55:03 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-desaturate) 22:55:04 INFO - PROCESS | 9223 | RSS: Main: 212373504 22:55:04 INFO - PROCESS | 9223 | 22:55:07 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51253/tests/kraken/driver.html?testName=json-parse-financial) 22:55:07 INFO - PROCESS | 9223 | RSS: Main: 212832256 22:55:07 INFO - PROCESS | 9223 | 22:55:09 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:55:09 INFO - PROCESS | 9223 | RSS: Main: 176386048 22:55:09 INFO - PROCESS | 9223 | 22:55:11 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:55:12 INFO - PROCESS | 9223 | RSS: Main: 182697984 22:55:12 INFO - PROCESS | 9223 | 22:55:14 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:55:14 INFO - PROCESS | 9223 | RSS: Main: 186880000 22:55:14 INFO - PROCESS | 9223 | 22:55:17 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:55:17 INFO - PROCESS | 9223 | RSS: Main: 182190080 22:55:17 INFO - PROCESS | 9223 | 22:55:20 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:55:21 INFO - PROCESS | 9223 | RSS: Main: 185171968 22:55:21 INFO - PROCESS | 9223 | 22:55:22 INFO - PROCESS | 9223 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51253/tests/kraken/driver.html?testName=ai-astar) 22:55:23 INFO - PROCESS | 9223 | RSS: Main: 236199936 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | __start_tp_report 22:55:23 INFO - PROCESS | 9223 | _x_x_mozilla_page_load 22:55:23 INFO - PROCESS | 9223 | _x_x_mozilla_page_load_details 22:55:23 INFO - PROCESS | 9223 | |i|pagename|runs| 22:55:23 INFO - PROCESS | 9223 | |0;ai-astar;100;111;108;111;113;113;110;110;109;109 22:55:23 INFO - PROCESS | 9223 | |1;audio-beat-detection;142;116;118;165;121;118;138;115;155;117 22:55:23 INFO - PROCESS | 9223 | |2;audio-dft;162;160;161;161;160;161;162;161;162;162 22:55:23 INFO - PROCESS | 9223 | |3;audio-fft;77;75;76;107;75;77;76;75;109;75 22:55:23 INFO - PROCESS | 9223 | |4;audio-oscillator;83;83;137;84;83;84;122;83;86;135 22:55:23 INFO - PROCESS | 9223 | |5;imaging-gaussian-blur;117;119;117;117;117;117;117;117;117;117 22:55:23 INFO - PROCESS | 9223 | |6;imaging-darkroom;147;146;146;147;148;146;148;146;147;146 22:55:23 INFO - PROCESS | 9223 | |7;imaging-desaturate;95;96;94;96;95;94;96;95;95;95 22:55:23 INFO - PROCESS | 9223 | |8;json-parse-financial;52;50;51;50;51;51;52;51;52;49 22:55:23 INFO - PROCESS | 9223 | |9;json-stringify-tinderbox;59;60;59;59;60;59;58;60;58;59 22:55:23 INFO - PROCESS | 9223 | |10;stanford-crypto-aes;75;73;74;73;73;72;72;73;74;77 22:55:23 INFO - PROCESS | 9223 | |11;stanford-crypto-ccm;129;117;118;123;116;120;119;115;159;121 22:55:23 INFO - PROCESS | 9223 | |12;stanford-crypto-pbkdf2;151;155;154;152;154;158;151;157;157;153 22:55:23 INFO - PROCESS | 9223 | |13;stanford-crypto-sha256-iterative;66;62;64;64;62;65;65;61;62;65 22:55:23 INFO - PROCESS | 9223 | __end_tp_report 22:55:23 INFO - PROCESS | 9223 | __start_cc_report 22:55:23 INFO - PROCESS | 9223 | _x_x_mozilla_cycle_collect,1603 22:55:23 INFO - PROCESS | 9223 | __end_cc_report 22:55:23 INFO - PROCESS | 9223 | __startTimestamp1472795723107__endTimestamp 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | ------- Summary: start ------- 22:55:23 INFO - PROCESS | 9223 | Number of tests: 14 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:3.69 (3.3%) stddev-sans-first:1.74 22:55:23 INFO - PROCESS | 9223 | Values: 100.0 111.0 108.0 111.0 113.0 113.0 110.0 110.0 109.0 109.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:129.50 stddev:18.30 (14.1%) stddev-sans-first:18.93 22:55:23 INFO - PROCESS | 9223 | Values: 142.0 116.0 118.0 165.0 121.0 118.0 138.0 115.0 155.0 117.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 22:55:23 INFO - PROCESS | 9223 | Values: 162.0 160.0 161.0 161.0 160.0 161.0 162.0 161.0 162.0 162.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.50 stddev:13.63 (18.1%) stddev-sans-first:14.32 22:55:23 INFO - PROCESS | 9223 | Values: 77.0 75.0 76.0 107.0 75.0 77.0 76.0 75.0 109.0 75.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:83.00 stddev:23.34 (28.1%) stddev-sans-first:24.11 22:55:23 INFO - PROCESS | 9223 | Values: 83.0 83.0 137.0 84.0 83.0 84.0 122.0 83.0 86.0 135.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 22:55:23 INFO - PROCESS | 9223 | Values: 117.0 119.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:55:23 INFO - PROCESS | 9223 | Values: 147.0 146.0 146.0 147.0 148.0 146.0 148.0 146.0 147.0 146.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 22:55:23 INFO - PROCESS | 9223 | Values: 95.0 96.0 94.0 96.0 95.0 94.0 96.0 95.0 95.0 95.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:0.97 22:55:23 INFO - PROCESS | 9223 | Values: 52.0 50.0 51.0 50.0 51.0 51.0 52.0 51.0 52.0 49.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 22:55:23 INFO - PROCESS | 9223 | Values: 59.0 60.0 59.0 59.0 60.0 59.0 58.0 60.0 58.0 59.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.50 stddev:1.51 (2.0%) stddev-sans-first:1.51 22:55:23 INFO - PROCESS | 9223 | Values: 75.0 73.0 74.0 73.0 73.0 72.0 72.0 73.0 74.0 77.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:120.50 stddev:13.04 (10.8%) stddev-sans-first:13.69 22:55:23 INFO - PROCESS | 9223 | Values: 129.0 117.0 118.0 123.0 116.0 120.0 119.0 115.0 159.0 121.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:154.50 stddev:2.53 (1.6%) stddev-sans-first:2.40 22:55:23 INFO - PROCESS | 9223 | Values: 151.0 155.0 154.0 152.0 154.0 158.0 151.0 157.0 157.0 153.0 22:55:23 INFO - PROCESS | 9223 | 22:55:23 INFO - PROCESS | 9223 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.50 stddev:1.71 (2.7%) stddev-sans-first:1.58 22:55:23 INFO - PROCESS | 9223 | Values: 66.0 62.0 64.0 64.0 62.0 65.0 65.0 61.0 62.0 65.0 22:55:23 INFO - PROCESS | 9223 | -------- Summary: end -------- 22:55:23 INFO - PROCESS | 9223 | 22:55:24 INFO - TEST-INFO | 9223: exit 0 22:55:24 INFO - TEST-OK | kraken | took 60422ms 22:55:24 INFO - SUITE-END | took 1174s 22:55:24 INFO - Completed test suite (00:19:34) 22:55:24 INFO - javascript benchmark 22:55:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.599300087489, 3210.743140548755, 3221.4040402525543, 3234.4339740545397, 3238.805671659702, 5564.86087847804, 5650.350209725882, 5658.547316214704, 5669.262995810549, 5676.006698827704, 5563.805266815662, 5618.5786066045, 5627.071533197028, 5640.492355687988, 5643.661380317184, 5044.39467263927, 5051.292819005343, 5085.79656813727, 5101.923471147936, 5158.226266060084, 5083.262926875601, 5121.795128194868, 5148.227765835136, 5169.147090730025, 5169.379674439054, 5060.51925067118, 5119.411267704206, 5141.614378921578, 5158.458361872005, 5175.948240517584, 5198.324217851655, 5204.0, 5214.478552144793, 5216.686998780086, 5258.605604579634, 4980.078685443204, 5038.899222015539, 5102.6173037022, 5133.6919784813035, 5138.152204886189, 5537.58468114893, 5582.190582365534, 5595.720213989325, 5602.719864006784, 5629.268195134607, 6082.074122787376, 6091.547368473436, 6106.413827121106, 6110.327863934964, 6116.357782432825, 5888.440598143169, 5971.402859714038, 6006.699665016732, 6033.909491357633, 6111.455355893571, 5648.406917273709, 5661.065924122515, 5673.26247587815, 5678.829635110953, 5680.488756011979, 6211.16149319845, 6215.9378406216265, 6229.96885015572, 6237.251529816361, 6242.937570624326, 5757.625754325955, 5831.416858314219, 5891.3224979127945, 5897.085951677497, 5978.671173085435, 5950.9107363389585, 5955.940440595626, 5972.104184372379, 5982.611130276518, 5997.850053748692, 5895.823125306248, 5916.083007133901, 5923.496502797224, 5951.494122999594, 5968.791092311835, 5786.42135786418, 5839.9124013139835, 5878.676672783038, 5937.70311484424, 5949.434803693643, 4562.749048802348, 4602.861914142581, 4603.58567728906, 4603.746793926366, 4613.40025796651, 4010.859619913289, 4048.6963477739287, 4125.339945608748, 4133.6693064554765, 4182.2890108681595, 5724.914126288109, 5730.799422020293, 5733.741981610838, 5748.425157484218, 5788.623739456922, 6047.2743270806905, 6081.939180608226, 6095.0, 6099.146119543268, 6114.541409394314, 5556.72216389187, 5675.46083122111, 5705.716213851933, 5713.200151978726, 5714.085746280658, 5266.8419947401635, 5277.736113194325, 5281.102212623863, 5297.073012222845, 5307.389650190277, 6301.653409062454, 6364.681765911686, 6369.235691716931, 6372.808815735535, 6386.074019267273, 5910.231669882888, 5988.850278743066, 6022.698865056817, 6113.0, 6117.96941015292, 6226.034964580497, 6230.626162430269, 6284.968575157095, 6289.056641503811, 6303.432691057827, 5908.202392676933, 5927.8221653350465, 5930.11048342752, 5931.258592675916, 5957.463828255478, 5819.79630712923, 5906.26171728534, 6015.789447369321, 6031.155638210742, 6048.818535443944, 5754.136879467946, 5877.882442351214, 5880.235569375954, 5907.70461476933, 5910.52715782745, 5806.390329015392, 5861.238039054895, 5862.062070068769, 5867.647941123546, 5870.412958704095, 5780.3930587289415, 5829.15477255804, 5841.4450627190345, 5845.918505076575, 5850.473457388855, 5887.0, 5916.875793599374, 5919.704014799329, 5980.970095149496, 6012.699365031818, 5775.48020678141, 5802.0426629605645, 5813.3895940925595, 5814.0, 5816.70916454184], "value": 5553.652401922952, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.5526227698087, 594.1147689941986, 624.2696045626616, 627.6955676496901, 628.9937100628993, 34506.48240276395, 44773.552264477345, 45467.31799023017, 45577.54422455774, 45622.0, 48452.75773621127, 48707.51292487074, 48708.0, 48763.75618121905, 48866.26700599494, 8455.0, 9122.407043542165, 9264.953675231649, 9298.16316531512, 10882.782344353109, 11062.446877656124, 11207.271527350696, 11209.103271738242, 11221.0, 11221.943890280538, 11229.32624042556, 11287.435628218598, 11300.830487542693, 11311.208215424924, 11322.773544529104, 11159.609413670523, 11183.161262905314, 11196.888031119666, 11208.887911120948, 11215.60745373908, 11054.778904421946, 11124.777504449947, 11133.443327833576, 11142.554297828157, 11147.498362573704, 45585.54414455846, 45849.54150458519, 45887.0, 45931.0, 45956.77021614904, 3737.383331750258, 3751.531058617673, 3786.4320351947154, 3812.6759225465867, 3842.5965273646425, 35671.82164089189, 35859.28281434383, 35943.0, 36238.27523449543, 37516.249675006344, 4236.301010333292, 4385.912281754379, 4400.977995110036, 4440.755758433284, 4487.057717879249, 355044.0, 355899.99999999744, 356027.0, 356206.0, 358441.0, 37523.4371484428, 38736.6126338733, 38803.0, 62580.68709656514, 62918.68540657267, 41419.37870931938, 41494.0, 41530.1693966125, 41533.0, 41579.168416631495, 16830.242639081273, 16937.491875243762, 16949.237284322237, 16979.660406791794, 16981.150942452827, 11539.249948753304, 11732.70668233301, 11796.88203117975, 12027.09796765246, 14986.550403487912, 9026.909730902607, 9372.484513351696, 9398.295127865438, 9455.479948602893, 9562.760930976781, 3491.0, 3561.661642143992, 3591.7069854852234, 3600.513930619385, 3627.093226693327, 1051.0562767549368, 1051.2378525568902, 1063.6808957312776, 1139.8746137924823, 1153.2792004996877, 34995.475067874, 36298.0, 36391.63608363883, 36417.45373819395, 36440.453393199125, 1187.1511869013696, 1198.101423932051, 1198.9580364687195, 1201.303244118409, 1239.4856134703996, 18525.42533884608, 18655.90672046631, 18807.717884231748, 18878.81121188798, 18908.621827563646, 698953.0, 707346.0, 708693.0, 709784.0, 711062.0, 65737.67131164313, 66222.33777662161, 66438.0, 66439.66780166069, 66804.6659766698, 1323.973520529584, 1406.324964017257, 1436.3249272841747, 1450.5285782120852, 1455.396010655566, 35139.82430087833, 36602.03432930701, 36802.447963280036, 36819.89540313795, 37012.0, 55451.16823247735, 56120.15819762625, 56171.43828561662, 56172.0, 56284.718576406856, 433906.0, 436248.0, 436652.0, 438057.0, 438267.0, 31312.0, 49301.26048109353, 49858.50141498539, 50559.49440505695, 54760.0, 50348.748256258485, 55341.72329138489, 55648.72175639096, 56315.43684563102, 62208.68895655493, 47077.76461117809, 48742.75628621834, 48887.0, 49591.256131158785, 50278.0, 48192.759036204596, 48861.75569122132, 49163.0, 49252.0, 49578.75210623924, 46773.29840052334, 47566.76216618895, 47845.28232076452, 47944.520554794006, 48062.0, 38138.80930595329, 40601.79699101485, 42184.156316872875, 42191.36712949247, 42732.35901461543, 30993.380132397677, 31244.218894527818, 31358.68641313649, 31476.37047259088, 31924.8403757989], "value": 21183.951473044326, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.66735108833024, 415.5553557693269, 421.58052737526157, 423.6780047164155, 439.6043560795284, 15329.616759581002, 20217.0, 20463.0, 20500.38498845035, 21544.676829847565, 19945.900270498627, 20178.0, 20236.29172978946, 20317.79682203181, 20551.177952881902, 5605.131204663284, 5721.971390143054, 5860.912086318687, 5867.941320586803, 5907.172995780573, 5846.619969701956, 5852.882942341151, 5873.1190321451695, 5874.500667443251, 5875.618084824473, 5743.684097374643, 5983.970080149593, 6024.939750602482, 6091.269047714259, 6091.573589848713, 6014.909776353358, 6036.486898613623, 6062.363451837559, 6222.5644204905675, 6569.605823650549, 5903.822885313448, 5913.142594323842, 5915.497182739472, 5919.260092488439, 5927.851803704899, 14795.334209960578, 23613.173538926054, 23738.893164486064, 24394.634080488806, 25966.0, 2427.080479238808, 2464.0267094497754, 2595.4679290745353, 2639.90443965754, 2836.177508522526, 10684.679459616145, 10831.891681083245, 11040.668779936615, 11154.274972126786, 11192.048675862561, 1872.8314451699275, 1879.915403806832, 1978.6834106542883, 1988.3537850198743, 2032.5528383755698, 60063.0, 65608.0, 66578.33421665769, 69448.30551694469, 79672.0, 10698.78602427947, 10997.725056873482, 11024.944875275733, 11484.310941343547, 11682.182247242781, 16166.27251773673, 16182.59543511398, 16203.918980405258, 16227.026378417097, 16293.429729959631, 8161.5919204039565, 8307.460015098999, 8387.370947178986, 8408.864803251605, 8502.787430314167, 8401.411901166859, 8921.776955576033, 8932.821343573092, 8934.955325223333, 9167.816643667089, 4844.709317440965, 4979.481258216279, 5008.973160502088, 5035.823746168867, 5100.209467532464, 1837.8281301445911, 1949.311904868793, 2046.2122082998153, 2071.2129390831387, 2113.9154433822473, 942.6606421688186, 1034.715453250362, 1038.5222797513077, 1042.4735508568276, 1072.099436473366, 20222.898885505772, 20265.79734202639, 20394.69407958882, 20552.0, 20707.896460517903, 1029.7631544744602, 1054.9683509494728, 1064.7018834726289, 1075.1452595186845, 1088.450332582041, 10775.946120269506, 11812.173147879737, 11864.347460889561, 11926.522939082339, 12001.699957501132, 532042.0, 540608.0, 544375.0, 555639.0, 558840.0, 90587.0, 92550.53724731333, 99858.00141998632, 101088.49455752675, 105846.0, 1077.272840832435, 1094.698957786599, 1121.85415895933, 1124.3591153042687, 1140.378493720924, 18972.241110355983, 19115.0, 19147.90426047861, 19321.51696207606, 19633.31283405074, 32356.191095222806, 37061.0, 44801.0, 45249.54750452585, 46045.0, 63778.68110659417, 64799.67600161969, 65272.347276528526, 66661.33338666745, 66690.33309667035, 7799.9220007799195, 8952.68665596701, 8971.596278167628, 9163.633454661738, 9329.253659707343, 12197.634070977885, 13815.240161791198, 14046.367913443715, 14520.782188267396, 14727.55817325482, 7492.700291988477, 11524.481398337262, 11628.592999244986, 12307.076969227164, 12440.129190956546, 11516.0, 11779.937853557294, 12042.63872083839, 12226.388680566113, 12463.252204867737, 12202.816957745463, 12332.691682708004, 12456.813147802972, 12668.303243321705, 12671.80992285098, 9022.954885225532, 9277.0, 9355.906440935503, 9475.668351607661, 9518.23854091657, 11769.941150294193, 12135.635930922086, 12211.206271592318, 12427.508698956182, 12428.56500022495], "value": 9636.838448721426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.0718865086626, 1587.3650539784085, 1772.6277481728837, 1821.1349608935766, 1893.6780747272953, 8473.406861519688, 9026.72919812405, 9035.864462033058, 9036.95481522593, 9040.954795226024, 8157.347412207024, 8845.823083538326, 8852.114788521145, 9086.13681700239, 9105.544722763869, 2426.4783071639595, 2451.82837201396, 2452.6443665668467, 2453.6196889482158, 2457.803375729937, 2316.721993360799, 2322.0479603362623, 2359.917402890899, 2361.8819059047064, 2363.865344634568, 2424.35754525051, 2441.633754936756, 2447.4003869052017, 2449.963250551234, 2452.8160387970975, 2254.8196144308417, 2260.3106052653934, 2264.784845439681, 2265.2411442166813, 2303.435658263736, 2315.79157875792, 2339.2865176121277, 2361.067378385528, 2361.8819059046978, 2365.47959448921, 9124.45253284805, 9212.585433655502, 9229.0, 9233.353665243436, 9245.167934885892, 5361.0, 5430.239766432703, 5499.340079190482, 5503.0, 5505.366882808448, 3560.2523470071314, 3563.8396272167815, 3575.302815950891, 3610.981945090284, 3628.328759179534, 1204.656672848234, 1212.078820096724, 1216.5924415320878, 1225.2525959164902, 1235.4440501774238, 13237.536686216032, 13310.068295219338, 13312.0, 13324.467021319233, 13337.73324533504, 4780.091782561302, 4818.807247710122, 4841.12859685253, 4943.456219815817, 4967.776450059756, 11046.226764126433, 11093.389863557582, 11119.72200694989, 11123.55505779776, 11146.665600032013, 5609.046462101453, 5617.747201375866, 5620.409856965041, 5621.4378562144275, 5630.887382252332, 4165.125323682, 4326.6754993375625, 4428.955710442919, 5044.646874718808, 5133.563647089964, 4037.2934736421007, 4063.146739184804, 4124.628783409507, 4129.194807012604, 4130.938035929464, 1164.6389619218069, 1174.7650469906052, 1187.121530067761, 1208.2629595946466, 1209.8366720492797, 786.5909726942073, 840.1472505407016, 844.7043534762906, 915.8855143107112, 992.5087081894509, 6228.813135605939, 6293.811185664437, 6393.360663933569, 6457.321981192001, 6638.17022872141, 977.032737589781, 985.4776968206719, 995.6963126246565, 1006.2201793609864, 1039.6932904793105, 4147.440095587118, 4362.563743625676, 4381.9342709859375, 4437.689361744647, 4439.356293337448, 11322.377269250106, 11861.703457413461, 11877.821832672516, 12101.576444824388, 12109.576164834365, 11909.047276217882, 11935.641930741918, 11990.100742444352, 12018.0, 12049.457774400173, 8652.394332396672, 8677.0, 8750.431221970552, 8870.467771933705, 8910.821783564292, 6261.217347831521, 6438.7746428876635, 6450.354964503512, 6483.513736469878, 6632.801015969529, 10777.137828973506, 10929.125669946226, 10932.289401188897, 10932.945335273273, 10936.781264374828, 10590.576376945144, 10772.569097236023, 10777.515011824644, 11146.832797507881, 11162.16283778736, 8396.37027222973, 8436.746897593082, 8458.873116903374, 8583.742487725129, 8599.0, 8718.782030449036, 8869.379143459895, 8873.157050080232, 8874.378793484653, 8879.733607991771, 7348.816279593053, 7441.739539116105, 7470.439717021137, 7503.849923001619, 7629.198934111839, 7288.416926645961, 7486.513376630502, 7936.127026027017, 8041.356691464552, 8331.416800823885, 7556.319931206218, 7588.354989825927, 7603.771886843404, 7683.961580192287, 7690.846373044088, 5677.971610142088, 5712.914306285492, 5722.0, 5799.0, 5871.442212989759, 5569.721513924206, 5643.322801263875, 5762.827115186551, 5803.47768700819, 5825.79609713658], "value": 4859.711127522317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.45369677000645, 357.4227622389841, 360.7691077710266, 363.7944561322854, 365.6855104610035, 5593.664380137189, 5598.608097433171, 5656.783791484825, 5666.291713535808, 5675.6878371689545, 5488.4237155098635, 5525.778968841241, 5548.805791797288, 5554.91667624986, 5557.666540007603, 4226.661867050644, 4335.6748243881675, 4366.716163449381, 4370.868873933787, 4395.538468460813, 4345.3047512398025, 4346.543612920629, 4353.129374125172, 4377.365282034103, 4408.4709834819705, 4303.289957157065, 4330.112326972963, 4372.191144638252, 4374.212641724488, 4385.407969924051, 4105.692073094515, 4294.892627684333, 4315.697901146921, 4355.695101342907, 4374.934375984363, 3807.7524960877454, 3813.31360355136, 3826.368649172883, 3826.7703937763545, 3833.6358045985585, 5394.031178619775, 5400.487863398254, 5405.702686352249, 5407.567394608422, 5409.83770486886, 4814.181589129857, 4846.224604063333, 4881.0, 4905.9264111038365, 4994.500549944976, 5675.574331925122, 5702.54379649627, 5713.885722285531, 5714.914276285859, 5743.339515955635, 4212.157568486315, 4212.9157416851795, 4218.303979843353, 4226.344916537942, 4229.667654688763, 5644.238027866227, 5739.995500787304, 5788.5369170466265, 5994.790182343597, 6047.546434017467, 4240.787960601958, 4245.745255284693, 4258.190943720683, 4292.59220374064, 4321.783910804447, 4361.454818147731, 4362.192994296065, 4367.366731823868, 4380.780960952004, 4381.539938306496, 4608.9308660370125, 4742.668013239109, 4901.36282283308, 4932.605391568666, 4969.155243608595, 5783.884322313615, 5816.447437493432, 5828.504577110908, 5833.766649334064, 5857.531397488191, 1486.420296084528, 1510.7431736604804, 1515.6589767302269, 1517.7419838627459, 1518.9696206075816, 967.4437198610827, 988.1699372527112, 997.8952210017843, 1028.681108856242, 1053.4311471805293, 5561.6663000220115, 5577.972110139423, 5590.413006634326, 5604.943950560442, 5614.494695477427, 5850.795222167289, 5893.204417403681, 5897.439743224387, 5935.139404786281, 5945.732442040118, 5412.431694672082, 5614.0, 5719.513841323451, 5751.971240143856, 5759.798407055817, 3181.2683082891062, 3199.6561444193494, 3200.5999250093737, 3213.903582892517, 3227.0, 6075.756969721162, 6109.877802443926, 6147.538934579924, 6275.748970041239, 6282.623042617458, 6069.514438844882, 6091.573589848668, 6099.542534309944, 6114.602550834182, 6119.173911521976, 6173.32093469727, 6179.011358182669, 6189.535784816067, 6191.876162476815, 6199.814005579839, 5675.687837168995, 5978.850528736816, 5985.910211346746, 5992.610480318765, 5994.730237139426, 5886.939571150235, 5897.705114744332, 5908.9068522323505, 5910.674912879833, 5979.5814292999075, 5977.551683623833, 6122.4795892347865, 6182.690865456799, 6210.627362358273, 6225.8132256032395, 4274.444322238089, 4289.378040184219, 4326.675499337625, 4352.760598167131, 4353.194658988097, 6089.716356366363, 6129.785457508966, 6136.14669178834, 6154.292256390572, 6154.476869466145, 5747.3103227612955, 5911.7635294587735, 5916.73374698131, 5920.230370051866, 5925.703714814328, 5799.652020878761, 5838.824835254949, 5861.736221870111, 5922.437368449991, 5922.881542369042, 5705.885882282414, 5749.942500575108, 5770.653760774367, 5815.505682017076, 5840.707964601838, 5833.037548804569, 5864.44287792659, 5876.912771137353, 5878.118282257696, 5889.882202356014, 5724.740557077436, 5744.712764361849, 5753.798617048383, 5767.5385969121535, 5771.942280577308], "value": 4462.367846821595, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.744480598999, 791.616066207889, 800.7437619961613, 846.1073567386405, 875.4178471316575, 16331.591710207236, 16925.99999999997, 16978.066206358646, 17077.316907323708, 17250.223739931702, 16139.919300403482, 16243.83756162438, 16252.674946501063, 16296.592585185377, 16313.184340782973, 721.1598487761764, 849.524266410812, 874.5802015032788, 875.7460336502438, 890.0209769253802, 899.415380003, 899.7840518275632, 900.7883147460342, 901.2699713232303, 902.463034494478, 943.0333907744582, 943.448082871521, 945.399671208784, 946.5314669238702, 947.6920000999668, 762.5653377574785, 901.4185850126664, 924.4591913730442, 926.1433174313801, 930.33481061041, 685.7702669605688, 686.6429456682547, 688.683205725367, 688.7141836137997, 690.1304356510782, 16154.596135096599, 16159.353625854952, 16188.0, 16194.83805161945, 16231.675366492722, 15828.891977561576, 16075.276612552463, 16274.75587866183, 16437.506874793777, 16567.585810354718, 14661.780073298909, 14837.0, 14906.552803415914, 14930.47743328989, 14964.700705985819, 4821.132196204681, 4895.583875370562, 4916.385451818523, 4980.477049909779, 5122.538971492546, 84823.57588212081, 90809.54595226982, 91829.54085229598, 92023.53988230016, 92894.5355273226, 12725.809112863315, 12757.936210319074, 12890.806637900252, 13046.54337098197, 13346.666333341744, 25056.749432505443, 25171.87414062918, 25231.495370092864, 25269.0, 25299.62050569243, 8834.823303533893, 8903.465792052497, 8973.192412682889, 9086.954565227132, 9091.227245684191, 5152.458991805881, 5180.844574662766, 5228.738563071831, 5230.764615592308, 5345.919811202835, 3657.762245454037, 3784.299904517673, 3804.410316400962, 3810.0665336992615, 3810.8856734298015, 572.8567858035491, 583.5419195931258, 588.5115354255958, 593.4836692077834, 596.4095545410099, 672.4653900149393, 723.5116296499947, 738.4240292571802, 745.0612228592013, 750.9399248060142, 9243.630254789867, 10024.79950400988, 10197.847032294521, 10261.58953641846, 10578.629747958785, 632.6513437653128, 654.0352979355508, 661.7915356662636, 754.479409207645, 768.769369189241, 4687.882802929886, 4909.4845041270155, 4938.358013458299, 5032.270320803463, 5039.647224694236, 73198.63400682963, 73834.63082684552, 74548.0, 74621.0, 75571.0, 106551.46724266329, 108818.0, 112760.0, 114137.0, 114410.0, 78810.6059469699, 79351.60324198342, 88518.0, 98928.0, 104361.0, 10396.399446375268, 10664.253502254767, 10779.892201078203, 11118.61084862026, 11214.215004949901, 41679.0, 44559.55440445554, 47417.0, 48565.27152092792, 53038.46961530489, 75803.0, 77216.0, 79732.60133699294, 80134.0, 81789.0, 13177.275249860966, 13416.932915335361, 13865.792013120014, 13977.301134943009, 14586.562403127924, 16026.67946641084, 16137.19314034317, 16666.16669166561, 16778.077205753314, 16800.57998550046, 14387.712245755236, 15240.923795380952, 16092.67814643724, 17005.149742512578, 17118.058506782247, 14012.43950241979, 14136.929315353358, 14318.212498312692, 14930.0, 15500.0, 14745.336459859547, 14915.254237288309, 15712.292946817191, 15805.52583422499, 16759.329626815277, 11809.940950295537, 12128.75742485163, 12330.0, 12424.937875310565, 12874.420651070912, 12737.363131843556, 12990.740185196431, 13181.538646147339, 13232.404541795835, 13295.0], "value": 8132.447702471522, "unit": "score"}], "name": "dromaeo_css", "value": 7647.04079081333, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 108.0, 111.0, 113.0, 113.0, 110.0, 110.0, 109.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 118.0, 165.0, 121.0, 118.0, 138.0, 115.0, 155.0, 117.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 107.0, 75.0, 77.0, 76.0, 75.0, 109.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 137.0, 84.0, 83.0, 84.0, 122.0, 83.0, 86.0, 135.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 148.0, 146.0, 148.0, 146.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 96.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 51.0, 51.0, 52.0, 51.0, 52.0, 49.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 58.0, 60.0, 58.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 73.0, 73.0, 72.0, 72.0, 73.0, 74.0, 77.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 117.0, 118.0, 123.0, 116.0, 120.0, 119.0, 115.0, 159.0, 121.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 155.0, 154.0, 152.0, 154.0, 158.0, 151.0, 157.0, 157.0, 153.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 64.0, 64.0, 62.0, 65.0, 65.0, 61.0, 62.0, 65.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1465.3999999999999, "alertThreshold": 2.0}]} 22:55:24 INFO - Return code: 0 22:55:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:55:24 INFO - # TBPL SUCCESS # 22:55:24 INFO - Running post-action listener: _resource_record_post_action 22:55:24 INFO - [mozharness: 2016-09-02 05:55:24.385110Z] Finished run-tests step (success) 22:55:24 INFO - Running post-run listener: _resource_record_post_run 22:55:24 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327680000; Read time: 8; Write time: 293312 22:55:24 INFO - TinderboxPrint: CPU usage
12.9% 22:55:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:55:24 INFO - TinderboxPrint: I/O write bytes / time
327,680,000 / 293,312 22:55:24 INFO - TinderboxPrint: CPU idle
8,280.4 (87.0%) 22:55:24 INFO - TinderboxPrint: CPU user
1,184.1 (12.4%) 22:55:24 INFO - TinderboxPrint: Swap in / out
0 / 0 22:55:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79319040; Read time: 0; Write time: 61484 22:55:24 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 248016896; Read time: 0; Write time: 231828 22:55:25 INFO - Running post-run listener: _upload_blobber_files 22:55:25 INFO - Blob upload gear active. 22:55:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:55:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:55:25 INFO - (blobuploader) - INFO - Open directory for files ... 22:55:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:55:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:55:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:55:29 INFO - (blobuploader) - INFO - Done attempting. 22:55:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:55:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:55:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:55:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:55:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:55:30 INFO - (blobuploader) - INFO - Done attempting. 22:55:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:55:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:55:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:55:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:55:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:55:31 INFO - (blobuploader) - INFO - Done attempting. 22:55:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:55:31 INFO - Return code: 0 22:55:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:55:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:55:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49abd24fdeb9e8ffd2a6977cc05994b9a62be15db86460693a97ad7ce995245a830af7bfc934c6f3b6536c139bb2e08b50ab789aefc859e839230c9da4041467", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78ef09477bb69de46b2ce3daa8efeffc05376bd7afaa6251a342e02b35fdcb47cacc76886c48fa960b178497073f832e81025b1bf6f768ece0b408f985d70c34", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/453493beb55b78fd26a1ba0ec61aed550c3769e2d2354e7b27fb82e8f22608e9d91993d1155731d8d34df86b099e66a0b520ed1e1f4a4952591d3a86e599e512"} 22:55:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:55:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:55:31 INFO - Contents: 22:55:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49abd24fdeb9e8ffd2a6977cc05994b9a62be15db86460693a97ad7ce995245a830af7bfc934c6f3b6536c139bb2e08b50ab789aefc859e839230c9da4041467", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78ef09477bb69de46b2ce3daa8efeffc05376bd7afaa6251a342e02b35fdcb47cacc76886c48fa960b178497073f832e81025b1bf6f768ece0b408f985d70c34", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/453493beb55b78fd26a1ba0ec61aed550c3769e2d2354e7b27fb82e8f22608e9d91993d1155731d8d34df86b099e66a0b520ed1e1f4a4952591d3a86e599e512"} 22:55:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:55:31 INFO - Copying logs to upload dir... 22:55:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:55:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.658449 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-01 22:55:29.747149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 22:55:29.751066) ========= 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=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 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-Aurora/sha512/49abd24fdeb9e8ffd2a6977cc05994b9a62be15db86460693a97ad7ce995245a830af7bfc934c6f3b6536c139bb2e08b50ab789aefc859e839230c9da4041467", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78ef09477bb69de46b2ce3daa8efeffc05376bd7afaa6251a342e02b35fdcb47cacc76886c48fa960b178497073f832e81025b1bf6f768ece0b408f985d70c34", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/453493beb55b78fd26a1ba0ec61aed550c3769e2d2354e7b27fb82e8f22608e9d91993d1155731d8d34df86b099e66a0b520ed1e1f4a4952591d3a86e599e512"} build_url:https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011465 build_url: 'https://queue.taskcluster.net/v1/task/AbKOL3BUTey09JnuTKNeLA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49abd24fdeb9e8ffd2a6977cc05994b9a62be15db86460693a97ad7ce995245a830af7bfc934c6f3b6536c139bb2e08b50ab789aefc859e839230c9da4041467", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78ef09477bb69de46b2ce3daa8efeffc05376bd7afaa6251a342e02b35fdcb47cacc76886c48fa960b178497073f832e81025b1bf6f768ece0b408f985d70c34", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/453493beb55b78fd26a1ba0ec61aed550c3769e2d2354e7b27fb82e8f22608e9d91993d1155731d8d34df86b099e66a0b520ed1e1f4a4952591d3a86e599e512"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 22:55:29.778562) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:55:29.779121) ========= 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=52faae308a556c440401f8e5000001e8-1472788763.670060-1555575885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 22:55:29.863858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 22:55:29.864214) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 22:55:29.864555) ========= ========= Total master_lag: 0.16 =========