builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1478388006.82 results: success (0) buildid: 20161103181821 builduid: d984d1bb7c314f73889b19df79c575c9 revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.820706) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.821134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.821410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.835824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.836141) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-05 16:20:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 213M=0s 2016-11-05 16:20:07 (213 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108470 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.958270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:06.958587) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015382 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:07.009692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:07.010284) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --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=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-05 16:20:07,475 truncating revision to first 12 chars 2016-11-05 16:20:07,475 Setting DEBUG logging. 2016-11-05 16:20:07,476 attempt 1/10 2016-11-05 16:20:07,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/24b8f08f7756?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 16:20:08,959 unpacking tar archive at: mozilla-beta-24b8f08f7756/testing/mozharness/ program finished with exit code 0 elapsedTime=1.815049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:08.839921) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:08.840268) ========= script_repo_revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:08.840659) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:08.840935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:08.851552) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:20:08.851866) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:09 INFO - MultiFileLogger online at 20161105 16:20:09 in /builds/slave/test 16:20:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:20:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:09 INFO - {'append_to_log': False, 16:20:09 INFO - 'base_work_dir': '/builds/slave/test', 16:20:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:20:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:09 INFO - 'branch': 'Mozilla-Beta', 16:20:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:09 INFO - 'config_files': ('talos/linux_config.py',), 16:20:09 INFO - 'default_actions': ('clobber', 16:20:09 INFO - 'read-buildbot-config', 16:20:09 INFO - 'download-and-extract', 16:20:09 INFO - 'populate-webroot', 16:20:09 INFO - 'create-virtualenv', 16:20:09 INFO - 'install', 16:20:09 INFO - 'run-tests'), 16:20:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:09 INFO - 'download_minidump_stackwalk': True, 16:20:09 INFO - 'download_symbols': 'ondemand', 16:20:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:09 INFO - 'installer_path': 'installer.exe', 16:20:09 INFO - 'log_level': 'info', 16:20:09 INFO - 'log_name': 'talos', 16:20:09 INFO - 'log_to_console': True, 16:20:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:09 INFO - 'opt_config_files': (), 16:20:09 INFO - 'pip_index': False, 16:20:09 INFO - 'sps_profile': False, 16:20:09 INFO - 'sps_profile_interval': 0, 16:20:09 INFO - 'suite': 'dromaeojs', 16:20:09 INFO - 'system_bits': '32', 16:20:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:09 INFO - 'title': 'talos-linux64-ix-006', 16:20:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:09 INFO - 'use_talos_json': True, 16:20:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:09 INFO - 'work_dir': 'build'} 16:20:09 INFO - [mozharness: 2016-11-05 23:20:09.411845Z] Running clobber step. 16:20:09 INFO - Running pre-action listener: _resource_record_pre_action 16:20:09 INFO - Running main action method: clobber 16:20:09 INFO - rmtree: /builds/slave/test/build 16:20:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:09 INFO - Running post-action listener: _resource_record_post_action 16:20:09 INFO - [mozharness: 2016-11-05 23:20:09.874294Z] Finished clobber step (success) 16:20:09 INFO - [mozharness: 2016-11-05 23:20:09.874397Z] Running read-buildbot-config step. 16:20:09 INFO - Running pre-action listener: _resource_record_pre_action 16:20:09 INFO - Running main action method: read_buildbot_config 16:20:09 INFO - Using buildbot properties: 16:20:09 INFO - { 16:20:09 INFO - "project": "", 16:20:09 INFO - "product": "firefox", 16:20:09 INFO - "script_repo_revision": "production", 16:20:09 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:20:09 INFO - "repository": "", 16:20:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:20:09 INFO - "buildid": "20161103181821", 16:20:09 INFO - "pgo_build": "True", 16:20:09 INFO - "basedir": "/builds/slave/test", 16:20:09 INFO - "buildnumber": 4, 16:20:09 INFO - "slavename": "talos-linux64-ix-006", 16:20:09 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:20:09 INFO - "platform": "ubuntu64_hw", 16:20:09 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:20:09 INFO - "branch": "mozilla-beta", 16:20:09 INFO - "repo_path": "releases/mozilla-beta", 16:20:09 INFO - "revision": "24b8f08f77565f859898b45f62d2132ccc64c6d8", 16:20:09 INFO - "stage_platform": "linux64-pgo", 16:20:09 INFO - "builduid": "d984d1bb7c314f73889b19df79c575c9", 16:20:09 INFO - "slavebuilddir": "test" 16:20:09 INFO - } 16:20:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:20:09 INFO - Running post-action listener: _resource_record_post_action 16:20:09 INFO - [mozharness: 2016-11-05 23:20:09.876839Z] Finished read-buildbot-config step (success) 16:20:09 INFO - [mozharness: 2016-11-05 23:20:09.876928Z] Running download-and-extract step. 16:20:09 INFO - Running pre-action listener: _resource_record_pre_action 16:20:09 INFO - Running main action method: download_and_extract 16:20:09 INFO - mkdir: /builds/slave/test/build/tests 16:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:09 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:20:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:20:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:20:09 INFO - retry: Failed, sleeping 30 seconds before retrying 16:20:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 16:20:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:20:39 INFO - retry: Failed, sleeping 60 seconds before retrying 16:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 16:21:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:21:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 16:21:39 INFO - Caught exception: HTTP Error 404: Not Found 16:21:39 INFO - Caught exception: HTTP Error 404: Not Found 16:21:39 INFO - Caught exception: HTTP Error 404: Not Found 16:21:39 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:21:40 INFO - Downloaded 1327 bytes. 16:21:40 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:40 INFO - Using the following test package requirements: 16:21:40 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:40 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:21:40 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:21:40 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'jsshell-linux-x86_64.zip'], 16:21:40 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:21:40 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:40 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:21:40 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:21:40 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:21:40 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:40 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:21:40 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:40 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:21:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:21:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:21:40 INFO - retry: Failed, sleeping 30 seconds before retrying 16:22:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:22:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:22:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:22:10 INFO - retry: Failed, sleeping 60 seconds before retrying 16:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:23:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:23:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:23:10 INFO - Caught exception: HTTP Error 404: Not Found 16:23:10 INFO - Caught exception: HTTP Error 404: Not Found 16:23:10 INFO - Caught exception: HTTP Error 404: Not Found 16:23:10 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:11 INFO - Downloaded 21787606 bytes. 16:23:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:23:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:23:12 INFO - Return code: 0 16:23:12 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:12 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:23:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:23:12 INFO - retry: Failed, sleeping 30 seconds before retrying 16:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:23:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:23:42 INFO - retry: Failed, sleeping 60 seconds before retrying 16:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:24:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:24:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:24:42 INFO - Caught exception: HTTP Error 404: Not Found 16:24:42 INFO - Caught exception: HTTP Error 404: Not Found 16:24:42 INFO - Caught exception: HTTP Error 404: Not Found 16:24:42 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:42 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:24:43 INFO - Downloaded 21787606 bytes. 16:24:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:24:45 INFO - Return code: 0 16:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:45 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:24:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:24:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:24:45 INFO - retry: Failed, sleeping 30 seconds before retrying 16:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 16:25:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:25:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:25:15 INFO - retry: Failed, sleeping 60 seconds before retrying 16:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 16:26:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:26:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 16:26:15 INFO - Caught exception: HTTP Error 404: Not Found 16:26:15 INFO - Caught exception: HTTP Error 404: Not Found 16:26:15 INFO - Caught exception: HTTP Error 404: Not Found 16:26:15 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:15 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:26:15 INFO - Downloaded 14413770 bytes. 16:26:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:26:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:26:16 INFO - Return code: 0 16:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:16 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:26:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:26:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:26:16 INFO - retry: Failed, sleeping 30 seconds before retrying 16:26:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 16:26:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:26:46 INFO - retry: Failed, sleeping 60 seconds before retrying 16:27:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 16:27:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:27:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 16:27:46 INFO - Caught exception: HTTP Error 404: Not Found 16:27:46 INFO - Caught exception: HTTP Error 404: Not Found 16:27:46 INFO - Caught exception: HTTP Error 404: Not Found 16:27:46 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:46 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:27:47 INFO - Downloaded 56799929 bytes. 16:27:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:47 INFO - mkdir: /builds/slave/test/properties 16:27:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:47 INFO - Contents: 16:27:47 INFO - build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:48 INFO - Running post-action listener: _resource_record_post_action 16:27:48 INFO - Running post-action listener: set_extra_try_arguments 16:27:48 INFO - [mozharness: 2016-11-05 23:27:48.060319Z] Finished download-and-extract step (success) 16:27:48 INFO - [mozharness: 2016-11-05 23:27:48.060555Z] Running populate-webroot step. 16:27:48 INFO - Running pre-action listener: _resource_record_pre_action 16:27:48 INFO - Running main action method: populate_webroot 16:27:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:48 INFO - '--tppagecycles', 16:27:48 INFO - '7'], 16:27:48 INFO - 'tests': ['tsvgm']}}, 16:27:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['damp', 'tps']}, 16:27:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'tests': ['damp', 'tps']}, 16:27:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['dromaeo_dom']}, 16:27:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['basic_compositor_video']}, 16:27:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['a11yr', 16:27:48 INFO - 'ts_paint', 16:27:48 INFO - 'tpaint', 16:27:48 INFO - 'sessionrestore', 16:27:48 INFO - 'sessionrestore_no_auto_restore', 16:27:48 INFO - 'tabpaint']}, 16:27:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['tsvgx', 16:27:48 INFO - 'tsvgr_opacity', 16:27:48 INFO - 'tart', 16:27:48 INFO - 'tscrollx', 16:27:48 INFO - 'cart']}, 16:27:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:48 INFO - 'tsvgr_opacity', 16:27:48 INFO - 'tart', 16:27:48 INFO - 'tscrollx', 16:27:48 INFO - 'cart']}, 16:27:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'talos_options': ['--disable-e10s'], 16:27:48 INFO - 'tests': ['tp5o']}, 16:27:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'tests': ['tp5o']}, 16:27:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'talos_options': ['--disable-e10s', 16:27:48 INFO - '--xperf_path', 16:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:48 INFO - 'tests': ['tp5n']}, 16:27:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:48 INFO - 'talos_options': ['--xperf_path', 16:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:48 INFO - 'tests': ['tp5n']}}, 16:27:48 INFO - 'talos.zip': {'path': '', 16:27:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:48 INFO - Running post-action listener: _resource_record_post_action 16:27:48 INFO - [mozharness: 2016-11-05 23:27:48.081970Z] Finished populate-webroot step (success) 16:27:48 INFO - [mozharness: 2016-11-05 23:27:48.082060Z] Running create-virtualenv step. 16:27:48 INFO - Running pre-action listener: _resource_record_pre_action 16:27:48 INFO - Running main action method: create_virtualenv 16:27:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:27:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:48 INFO - Using real prefix '/usr' 16:27:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:48 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:50 INFO - Installing pip.................done. 16:27:50 INFO - Return code: 0 16:27:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:27:50 INFO - Using env: {'DISPLAY': ':0', 16:27:50 INFO - 'HOME': '/home/cltbld', 16:27:50 INFO - 'LANG': 'en_US.UTF-8', 16:27:50 INFO - 'LANGUAGE': 'en_US:en', 16:27:50 INFO - 'LOGNAME': 'cltbld', 16:27:50 INFO - 'MAIL': '/var/mail/cltbld', 16:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:50 INFO - 'MOZ_NO_REMOTE': '1', 16:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:50 INFO - 'NO_EM_RESTART': '1', 16:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:50 INFO - 'PWD': '/builds/slave/test', 16:27:50 INFO - 'SHELL': '/bin/bash', 16:27:50 INFO - 'SHLVL': '1', 16:27:50 INFO - 'TERM': 'linux', 16:27:50 INFO - 'TMOUT': '86400', 16:27:50 INFO - 'USER': 'cltbld', 16:27:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:50 INFO - '_': '/tools/buildbot/bin/python'} 16:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:27:50 INFO - Cleaning up... 16:27:50 INFO - Return code: 0 16:27:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:50 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:27:50 INFO - Using env: {'DISPLAY': ':0', 16:27:50 INFO - 'HOME': '/home/cltbld', 16:27:50 INFO - 'LANG': 'en_US.UTF-8', 16:27:50 INFO - 'LANGUAGE': 'en_US:en', 16:27:50 INFO - 'LOGNAME': 'cltbld', 16:27:50 INFO - 'MAIL': '/var/mail/cltbld', 16:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:50 INFO - 'MOZ_NO_REMOTE': '1', 16:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:50 INFO - 'NO_EM_RESTART': '1', 16:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:50 INFO - 'PWD': '/builds/slave/test', 16:27:50 INFO - 'SHELL': '/bin/bash', 16:27:50 INFO - 'SHLVL': '1', 16:27:50 INFO - 'TERM': 'linux', 16:27:50 INFO - 'TMOUT': '86400', 16:27:50 INFO - 'USER': 'cltbld', 16:27:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:50 INFO - '_': '/tools/buildbot/bin/python'} 16:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:51 INFO - Downloading/unpacking psutil>=3.1.1 16:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:53 INFO - Installing collected packages: psutil 16:27:53 INFO - Running setup.py install for psutil 16:27:53 INFO - building 'psutil._psutil_linux' extension 16:27:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:27:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:27:53 INFO - building 'psutil._psutil_posix' extension 16:27:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:27:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:53 INFO - Successfully installed psutil 16:27:53 INFO - Cleaning up... 16:27:53 INFO - Return code: 0 16:27:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:27:53 INFO - Using env: {'DISPLAY': ':0', 16:27:53 INFO - 'HOME': '/home/cltbld', 16:27:53 INFO - 'LANG': 'en_US.UTF-8', 16:27:53 INFO - 'LANGUAGE': 'en_US:en', 16:27:53 INFO - 'LOGNAME': 'cltbld', 16:27:53 INFO - 'MAIL': '/var/mail/cltbld', 16:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:53 INFO - 'MOZ_NO_REMOTE': '1', 16:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:53 INFO - 'NO_EM_RESTART': '1', 16:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:53 INFO - 'PWD': '/builds/slave/test', 16:27:53 INFO - 'SHELL': '/bin/bash', 16:27:53 INFO - 'SHLVL': '1', 16:27:53 INFO - 'TERM': 'linux', 16:27:53 INFO - 'TMOUT': '86400', 16:27:53 INFO - 'USER': 'cltbld', 16:27:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:53 INFO - '_': '/tools/buildbot/bin/python'} 16:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:55 INFO - Installing collected packages: mozsystemmonitor 16:27:55 INFO - Running setup.py install for mozsystemmonitor 16:27:55 INFO - Successfully installed mozsystemmonitor 16:27:55 INFO - Cleaning up... 16:27:55 INFO - Return code: 0 16:27:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:55 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:27:55 INFO - Using env: {'DISPLAY': ':0', 16:27:55 INFO - 'HOME': '/home/cltbld', 16:27:55 INFO - 'LANG': 'en_US.UTF-8', 16:27:55 INFO - 'LANGUAGE': 'en_US:en', 16:27:55 INFO - 'LOGNAME': 'cltbld', 16:27:55 INFO - 'MAIL': '/var/mail/cltbld', 16:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:55 INFO - 'MOZ_NO_REMOTE': '1', 16:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:55 INFO - 'NO_EM_RESTART': '1', 16:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:55 INFO - 'PWD': '/builds/slave/test', 16:27:55 INFO - 'SHELL': '/bin/bash', 16:27:55 INFO - 'SHLVL': '1', 16:27:55 INFO - 'TERM': 'linux', 16:27:55 INFO - 'TMOUT': '86400', 16:27:55 INFO - 'USER': 'cltbld', 16:27:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:55 INFO - '_': '/tools/buildbot/bin/python'} 16:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:56 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:58 INFO - warning: no files found matching '*.txt' 16:27:58 INFO - no previously-included directories found matching 'build' 16:27:58 INFO - no previously-included directories found matching 'dist' 16:27:58 INFO - no previously-included directories found matching '.git*' 16:27:58 INFO - Installing collected packages: jsonschema, functools32 16:27:58 INFO - Running setup.py install for functools32 16:27:58 INFO - warning: no files found matching '*.txt' 16:27:58 INFO - no previously-included directories found matching 'build' 16:27:58 INFO - no previously-included directories found matching 'dist' 16:27:58 INFO - no previously-included directories found matching '.git*' 16:27:58 INFO - Successfully installed jsonschema functools32 16:27:58 INFO - Cleaning up... 16:27:58 INFO - Return code: 0 16:27:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:27:58 INFO - Using env: {'DISPLAY': ':0', 16:27:58 INFO - 'HOME': '/home/cltbld', 16:27:58 INFO - 'LANG': 'en_US.UTF-8', 16:27:58 INFO - 'LANGUAGE': 'en_US:en', 16:27:58 INFO - 'LOGNAME': 'cltbld', 16:27:58 INFO - 'MAIL': '/var/mail/cltbld', 16:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:58 INFO - 'MOZ_NO_REMOTE': '1', 16:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:58 INFO - 'NO_EM_RESTART': '1', 16:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:58 INFO - 'PWD': '/builds/slave/test', 16:27:58 INFO - 'SHELL': '/bin/bash', 16:27:58 INFO - 'SHLVL': '1', 16:27:58 INFO - 'TERM': 'linux', 16:27:58 INFO - 'TMOUT': '86400', 16:27:58 INFO - 'USER': 'cltbld', 16:27:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:58 INFO - '_': '/tools/buildbot/bin/python'} 16:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:58 INFO - Cleaning up... 16:27:58 INFO - Return code: 0 16:27:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:27:58 INFO - Using env: {'DISPLAY': ':0', 16:27:58 INFO - 'HOME': '/home/cltbld', 16:27:58 INFO - 'LANG': 'en_US.UTF-8', 16:27:58 INFO - 'LANGUAGE': 'en_US:en', 16:27:58 INFO - 'LOGNAME': 'cltbld', 16:27:58 INFO - 'MAIL': '/var/mail/cltbld', 16:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:58 INFO - 'MOZ_NO_REMOTE': '1', 16:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:58 INFO - 'NO_EM_RESTART': '1', 16:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:58 INFO - 'PWD': '/builds/slave/test', 16:27:58 INFO - 'SHELL': '/bin/bash', 16:27:58 INFO - 'SHLVL': '1', 16:27:58 INFO - 'TERM': 'linux', 16:27:58 INFO - 'TMOUT': '86400', 16:27:58 INFO - 'USER': 'cltbld', 16:27:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:58 INFO - '_': '/tools/buildbot/bin/python'} 16:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:58 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:01 INFO - Downloading docopt-0.6.1.tar.gz 16:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:01 INFO - Running setup.py install for blobuploader 16:28:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:01 INFO - Running setup.py install for requests 16:28:02 INFO - Running setup.py install for docopt 16:28:02 INFO - Successfully installed blobuploader requests docopt 16:28:02 INFO - Cleaning up... 16:28:02 INFO - Return code: 0 16:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:02 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:28:02 INFO - Using env: {'DISPLAY': ':0', 16:28:02 INFO - 'HOME': '/home/cltbld', 16:28:02 INFO - 'LANG': 'en_US.UTF-8', 16:28:02 INFO - 'LANGUAGE': 'en_US:en', 16:28:02 INFO - 'LOGNAME': 'cltbld', 16:28:02 INFO - 'MAIL': '/var/mail/cltbld', 16:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:02 INFO - 'MOZ_NO_REMOTE': '1', 16:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:02 INFO - 'NO_EM_RESTART': '1', 16:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:02 INFO - 'PWD': '/builds/slave/test', 16:28:02 INFO - 'SHELL': '/bin/bash', 16:28:02 INFO - 'SHLVL': '1', 16:28:02 INFO - 'TERM': 'linux', 16:28:02 INFO - 'TMOUT': '86400', 16:28:02 INFO - 'USER': 'cltbld', 16:28:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:02 INFO - '_': '/tools/buildbot/bin/python'} 16:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:02 INFO - Running setup.py (path:/tmp/pip-9PX5GM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:02 INFO - Running setup.py (path:/tmp/pip-bEFL6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:02 INFO - Running setup.py (path:/tmp/pip-TYSEl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:02 INFO - Running setup.py (path:/tmp/pip-CPTEKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:02 INFO - Running setup.py (path:/tmp/pip-ddsymb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:02 INFO - Running setup.py (path:/tmp/pip-qtW0bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:02 INFO - Running setup.py (path:/tmp/pip-HhV_il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:03 INFO - Running setup.py (path:/tmp/pip-hu1DmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:03 INFO - Running setup.py (path:/tmp/pip-1ZF7pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:03 INFO - Running setup.py (path:/tmp/pip-WKWpC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:03 INFO - Running setup.py (path:/tmp/pip-EPJp2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:03 INFO - Running setup.py (path:/tmp/pip-L07_E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:03 INFO - Running setup.py (path:/tmp/pip-mUJ3lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:03 INFO - Running setup.py (path:/tmp/pip-HOQglF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:03 INFO - Running setup.py (path:/tmp/pip-vySaAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:03 INFO - Running setup.py (path:/tmp/pip-lTkQMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:03 INFO - Running setup.py (path:/tmp/pip-QIHPz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:03 INFO - Running setup.py install for manifestparser 16:28:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozcrash 16:28:03 INFO - Running setup.py install for mozdebug 16:28:04 INFO - Running setup.py install for mozdevice 16:28:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for mozfile 16:28:04 INFO - Running setup.py install for mozhttpd 16:28:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for mozinfo 16:28:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for mozInstall 16:28:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for mozleak 16:28:04 INFO - Running setup.py install for mozlog 16:28:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for moznetwork 16:28:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Running setup.py install for mozprocess 16:28:04 INFO - Running setup.py install for mozprofile 16:28:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Running setup.py install for mozrunner 16:28:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Running setup.py install for mozscreenshot 16:28:05 INFO - Running setup.py install for moztest 16:28:05 INFO - Running setup.py install for mozversion 16:28:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:05 INFO - Cleaning up... 16:28:05 INFO - Return code: 0 16:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:05 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:28:05 INFO - Using env: {'DISPLAY': ':0', 16:28:05 INFO - 'HOME': '/home/cltbld', 16:28:05 INFO - 'LANG': 'en_US.UTF-8', 16:28:05 INFO - 'LANGUAGE': 'en_US:en', 16:28:05 INFO - 'LOGNAME': 'cltbld', 16:28:05 INFO - 'MAIL': '/var/mail/cltbld', 16:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:05 INFO - 'MOZ_NO_REMOTE': '1', 16:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:05 INFO - 'NO_EM_RESTART': '1', 16:28:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:05 INFO - 'PWD': '/builds/slave/test', 16:28:05 INFO - 'SHELL': '/bin/bash', 16:28:05 INFO - 'SHLVL': '1', 16:28:05 INFO - 'TERM': 'linux', 16:28:05 INFO - 'TMOUT': '86400', 16:28:05 INFO - 'USER': 'cltbld', 16:28:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:05 INFO - '_': '/tools/buildbot/bin/python'} 16:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:05 INFO - Running setup.py (path:/tmp/pip-HWlV78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:05 INFO - Running setup.py (path:/tmp/pip-bKdrZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:05 INFO - Running setup.py (path:/tmp/pip-9kWGU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:05 INFO - Running setup.py (path:/tmp/pip-DnxVL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:06 INFO - Running setup.py (path:/tmp/pip-evBzFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:06 INFO - Running setup.py (path:/tmp/pip-XiZ3kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:06 INFO - Running setup.py (path:/tmp/pip-GNfmc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:06 INFO - Running setup.py (path:/tmp/pip-WyfRRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:06 INFO - Running setup.py (path:/tmp/pip-wPn6PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:06 INFO - Running setup.py (path:/tmp/pip-H8_Vjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:06 INFO - Running setup.py (path:/tmp/pip-SgACBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:06 INFO - Running setup.py (path:/tmp/pip-yKfTmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:06 INFO - Running setup.py (path:/tmp/pip-FJys4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:06 INFO - Running setup.py (path:/tmp/pip-qyta6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:06 INFO - Running setup.py (path:/tmp/pip-JotuAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:06 INFO - Running setup.py (path:/tmp/pip-gvHHJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:06 INFO - Running setup.py (path:/tmp/pip-o5R3uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:28:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:08 INFO - Downloading blessings-1.6.tar.gz 16:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:09 INFO - Installing collected packages: blessings 16:28:09 INFO - Running setup.py install for blessings 16:28:09 INFO - Successfully installed blessings 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:09 INFO - Reading from file tmpfile_stdout 16:28:09 INFO - Current package versions: 16:28:09 INFO - argparse == 1.2.1 16:28:09 INFO - blessings == 1.6 16:28:09 INFO - blobuploader == 1.2.4 16:28:09 INFO - docopt == 0.6.1 16:28:09 INFO - functools32 == 3.2.3-2 16:28:09 INFO - jsonschema == 2.5.1 16:28:09 INFO - manifestparser == 1.1 16:28:09 INFO - mozInstall == 1.12 16:28:09 INFO - mozcrash == 0.17 16:28:09 INFO - mozdebug == 0.1 16:28:09 INFO - mozdevice == 0.48 16:28:09 INFO - mozfile == 1.2 16:28:09 INFO - mozhttpd == 0.7 16:28:09 INFO - mozinfo == 0.9 16:28:09 INFO - mozleak == 0.1 16:28:09 INFO - mozlog == 3.3 16:28:09 INFO - moznetwork == 0.27 16:28:09 INFO - mozprocess == 0.23 16:28:09 INFO - mozprofile == 0.28 16:28:09 INFO - mozrunner == 6.12 16:28:09 INFO - mozscreenshot == 0.1 16:28:09 INFO - mozsystemmonitor == 0.3 16:28:09 INFO - moztest == 0.7 16:28:09 INFO - mozversion == 1.4 16:28:09 INFO - psutil == 3.1.1 16:28:09 INFO - requests == 1.2.3 16:28:09 INFO - wsgiref == 0.1.2 16:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:28:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:28:09 INFO - Using env: {'DISPLAY': ':0', 16:28:09 INFO - 'HOME': '/home/cltbld', 16:28:09 INFO - 'LANG': 'en_US.UTF-8', 16:28:09 INFO - 'LANGUAGE': 'en_US:en', 16:28:09 INFO - 'LOGNAME': 'cltbld', 16:28:09 INFO - 'MAIL': '/var/mail/cltbld', 16:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:09 INFO - 'MOZ_NO_REMOTE': '1', 16:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:09 INFO - 'NO_EM_RESTART': '1', 16:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:09 INFO - 'PWD': '/builds/slave/test', 16:28:09 INFO - 'SHELL': '/bin/bash', 16:28:09 INFO - 'SHLVL': '1', 16:28:09 INFO - 'TERM': 'linux', 16:28:09 INFO - 'TMOUT': '86400', 16:28:09 INFO - 'USER': 'cltbld', 16:28:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:09 INFO - '_': '/tools/buildbot/bin/python'} 16:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:09 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 0x265b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x291c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:28:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:28:09 INFO - Using env: {'DISPLAY': ':0', 16:28:09 INFO - 'HOME': '/home/cltbld', 16:28:09 INFO - 'LANG': 'en_US.UTF-8', 16:28:09 INFO - 'LANGUAGE': 'en_US:en', 16:28:09 INFO - 'LOGNAME': 'cltbld', 16:28:09 INFO - 'MAIL': '/var/mail/cltbld', 16:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:09 INFO - 'MOZ_NO_REMOTE': '1', 16:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:09 INFO - 'NO_EM_RESTART': '1', 16:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:09 INFO - 'PWD': '/builds/slave/test', 16:28:09 INFO - 'SHELL': '/bin/bash', 16:28:09 INFO - 'SHLVL': '1', 16:28:09 INFO - 'TERM': 'linux', 16:28:09 INFO - 'TMOUT': '86400', 16:28:09 INFO - 'USER': 'cltbld', 16:28:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:09 INFO - '_': '/tools/buildbot/bin/python'} 16:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Running post-action listener: _resource_record_post_action 16:28:09 INFO - Running post-action listener: _start_resource_monitoring 16:28:09 INFO - Starting resource monitoring. 16:28:09 INFO - [mozharness: 2016-11-05 23:28:09.871010Z] Finished create-virtualenv step (success) 16:28:09 INFO - [mozharness: 2016-11-05 23:28:09.871434Z] Running install step. 16:28:09 INFO - Running pre-action listener: _resource_record_pre_action 16:28:09 INFO - Running main action method: install 16:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:10 INFO - Reading from file tmpfile_stdout 16:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:10 INFO - Reading from file tmpfile_stdout 16:28:10 INFO - Output received: 16:28:10 INFO - Usage: mozinstall [options] installer 16:28:10 INFO - Options: 16:28:10 INFO - -h, --help show this help message and exit 16:28:10 INFO - -d DEST, --destination=DEST 16:28:10 INFO - Directory to install application into. [default: 16:28:10 INFO - "/builds/slave/test"] 16:28:10 INFO - --app=APP Application being installed. [default: firefox] 16:28:10 INFO - mkdir: /builds/slave/test/build/application 16:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:28:24 INFO - Reading from file tmpfile_stdout 16:28:24 INFO - Output received: 16:28:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:24 INFO - Running post-action listener: _resource_record_post_action 16:28:24 INFO - [mozharness: 2016-11-05 23:28:24.199709Z] Finished install step (success) 16:28:24 INFO - [mozharness: 2016-11-05 23:28:24.199990Z] Running run-tests step. 16:28:24 INFO - Running pre-action listener: _resource_record_pre_action 16:28:24 INFO - Running main action method: run_tests 16:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:24 INFO - Python 2.7.3 16:28:24 INFO - Return code: 0 16:28:24 INFO - grabbing minidump binary from tooltool 16:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:24 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 0x291a3b0>, '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 0x291b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x291b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:28:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:28:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:28:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:24 INFO - Return code: 0 16:28:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:28:24 INFO - Using env: {'DISPLAY': ':0', 16:28:24 INFO - 'HOME': '/home/cltbld', 16:28:24 INFO - 'LANG': 'en_US.UTF-8', 16:28:24 INFO - 'LANGUAGE': 'en_US:en', 16:28:24 INFO - 'LOGNAME': 'cltbld', 16:28:24 INFO - 'MAIL': '/var/mail/cltbld', 16:28:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:24 INFO - 'MOZ_NO_REMOTE': '1', 16:28:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:24 INFO - 'NO_EM_RESTART': '1', 16:28:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:24 INFO - 'PWD': '/builds/slave/test', 16:28:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:24 INFO - 'SHELL': '/bin/bash', 16:28:24 INFO - 'SHLVL': '1', 16:28:24 INFO - 'TERM': 'linux', 16:28:24 INFO - 'TMOUT': '86400', 16:28:24 INFO - 'USER': 'cltbld', 16:28:24 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895', 16:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:24 INFO - '_': '/tools/buildbot/bin/python'} 16:28:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:28:24 INFO - mozversion application_buildid: 20161103181821 16:28:24 INFO - mozversion application_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:24 INFO - mozversion application_display_name: Firefox 16:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:24 INFO - mozversion application_name: Firefox 16:28:24 INFO - mozversion application_remotingname: firefox 16:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:24 INFO - mozversion application_vendor: Mozilla 16:28:24 INFO - mozversion application_version: 50.0 16:28:24 INFO - mozversion platform_buildid: 20161103181821 16:28:24 INFO - mozversion platform_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:24 INFO - mozversion platform_version: 50.0 16:28:24 INFO - using testdate: 1478388504 16:28:24 INFO - actual date: 1478388504 16:28:24 INFO - starting webserver on 'localhost:42450' 16:28:24 INFO - SUITE-START | Running 2 tests 16:28:24 INFO - TEST-START | dromaeo_css 16:28:24 INFO - operating with platform_type : linux_ 16:28:24 INFO - Initialising browser for dromaeo_css test... 16:28:24 INFO - TEST-INFO | started process 2327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk7RC8_/profile http://localhost:42450/getInfo.html) 16:28:28 INFO - PROCESS | 2327 | __metrics Screen width/height:1600/1200 16:28:28 INFO - PROCESS | 2327 | colorDepth:24 16:28:28 INFO - PROCESS | 2327 | Browser inner width/height: 1024/697 16:28:28 INFO - PROCESS | 2327 | __metrics 16:28:29 INFO - PROCESS | 2327 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:28:29 INFO - PROCESS | 2327 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:30 INFO - TEST-INFO | 2327: exit 0 16:28:30 INFO - Browser initialized. 16:28:30 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:30 INFO - TEST-INFO | started process 2500 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpk7RC8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:42 INFO - PROCESS | 2500 | RSS: Main: 144338944 16:28:42 INFO - PROCESS | 2500 | 16:29:32 INFO - PROCESS | 2500 | 1478388572893 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:31:41 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42450/tests/dromaeo/cssquery-ext.html) 16:31:42 INFO - PROCESS | 2500 | RSS: Main: 249393152 16:31:42 INFO - PROCESS | 2500 | 16:34:45 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-ext.html (next: http://localhost:42450/tests/dromaeo/cssquery-jquery.html) 16:34:45 INFO - PROCESS | 2500 | RSS: Main: 234651648 16:34:45 INFO - PROCESS | 2500 | 16:37:47 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42450/tests/dromaeo/cssquery-mootools.html) 16:37:48 INFO - PROCESS | 2500 | RSS: Main: 223330304 16:37:48 INFO - PROCESS | 2500 | 16:40:50 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42450/tests/dromaeo/cssquery-prototype.html) 16:40:50 INFO - PROCESS | 2500 | RSS: Main: 222265344 16:40:50 INFO - PROCESS | 2500 | 16:43:53 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42450/tests/dromaeo/cssquery-yui.html) 16:43:53 INFO - PROCESS | 2500 | RSS: Main: 176467968 16:43:53 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | Cycle 1(1): loaded http://localhost:42450/tests/dromaeo/cssquery-yui.html (next: http://localhost:42450/tests/dromaeo/cssquery-dojo.html) 16:46:56 INFO - PROCESS | 2500 | RSS: Main: 257679360 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | __start_tp_report 16:46:56 INFO - PROCESS | 2500 | _x_x_mozilla_page_load 16:46:56 INFO - PROCESS | 2500 | _x_x_mozilla_page_load_details 16:46:56 INFO - PROCESS | 2500 | |i|pagename|runs| 16:46:56 INFO - PROCESS | 2500 | |0;dojo.html;3182.729467995221;3186.0601122668813;3215.340855124701;3226.2095786532273;3248.707616314531;5525.91711124333;5627.831165065049;5630.915536266949;5638.689872057045;5651.717414129293;5636.802711905084;5727.097823006831;5729.255196824417;5734.741936612852;5735.168400581913;5169.819056333029;5173.534381905628;5181.352330958621;5228.895422091556;5237.580993520528;4995.800167993276;5046.97476512617;5252.107141785887;5253.8949221015555;5290.523852853243;5049.4698056704065;5088.618353623475;5125.384953805548;5135.972805438909;5174.534291913727;5085.288059671649;5128.512791284822;5207.557357624606;5210.895782084375;5236.73816309183;4946.975265123651;5029.152698185891;5106.846794596168;5159.406668233126;5176.844694659128;5527.944720552783;5542.085555883315;5551.361593416768;5552.194931734876;5559.193916882029;5979.790707325223;6037.54718396122;6096.1770161028135;6098.847528811771;6104.3590423005835;5921.467067963904;5985.640861548278;6120.081987701836;6129.938700612981;6133;5571.470710282517;5660.235868157828;5678.659280443187;5687.573431992617;5713.3429655589725;6205.217322026895;6230.158928544589;6231.158793562901;6235.314115447297;6238.220222472191;5713.457221563945;5890.558208134321;5940.198073260054;5954.047352423591;6005.069214271794;6070.91887144588;6088.908666370008;6093.025115981422;6112.144299798032;6132.877342453126;6022.668753218528;6024.759009639565;6035.034394496859;6035.939640603538;6041.577089603774;5748.051571490741;5870.559708021916;5872.64764114146;6036.607620504653;6068.878622427526;4589.609883159968;4607;4607.101615185007;4612.7924243409125;4616.215243408628;4146.460960075231;4147.958520414818;4157.355609880473;4168.937465938014;4206.894827629272;5790.334111577138;5795.565332600072;5805.10020946754;5811.128330750421;5821.388754180834;5896.616719913192;5996.4603185713495;6045.576809623372;6111.9694401528595;6127.509799216052;5800.071988481823;5800.970995144998;5828.358880523139;5832.825015249549;5835.766569337264;5278.656887302313;5282;5296.814611488579;5297.576193904479;5298.523132918056;5972.85067873298;6172.444479996732;6300.1494798202575;6304.810855674337;6314.589551679126;6076.058210977306;6127.930662619259;6153.384661533811;6154.815355539341;6165.722542485689;6261.624302541862;6262.405071518198;6274.152989346562;6277.74889004453;6325.399087086719;5720.628159169641;5859.619124756877;6002.489788367863;6003.519718422428;6010.368911264254;6002.429769171922;6097.512199023979;6101.908471373022;6132.754689812357;6139.938600613937;5935.317438494456;5970.402959704169;5975.880482390415;5978.043513037893;5981.7906373278465;5747.48272655463;5783.161441590862;5924.763009479745;5993.850153746192;5994.07091900756;5755.827325180251;5839.065749480063;5844.415558444121;5871.882562348815;5877.0302900021015;5878.676672783038;5901;5901.763929442946;5906.2026626406605;5941.0197317442135;5714.399988001366;5757.942420575908;5758.1650660654805;5763.94236057634;5767.884642307215 16:46:56 INFO - PROCESS | 2500 | |1;ext.html;598.2491972574421;639.974401023959;663.4924282923564;671.5232185148544;679.6601699150425;34153;44779.55220447794;45533;45710.542894571045;45733.771331143296;47683.0463390732;48072;48114.75942620291;48158.27762583555;48262.51737482624;8417.621207045668;9093.95453022734;9205.585748641293;9246.67636632718;9269.72190834276;10785.245032847704;11091.611793587226;11095.83356249657;11463.140264480156;11465.197436179471;11306;11389.088872890148;11402.258853174559;11407.657770266906;11425.542978280859;11148.944255278753;11210.495527701192;11231.10151187903;11232.269902456314;11233.382163980978;11052.392118433483;11083.279586826831;11089.72275693106;11094.722631934266;11095.278806877526;44984.55015449869;45231.77384113058;45331;45416;45423;3669.284489524544;3819.866304679323;4173.353130264813;4267.679924005712;4432.867013989586;35541.82229088838;35556.111097222514;35655.10862228439;35695.82152089249;35938;4442.400275962736;4446.288593824973;4446.710963787344;4544.182047231497;4665.650076244296;370352;371555;371904;372115;372835;63699.6815015922;63989;64097.35902640914;64160;64252.35747642464;39212.80393598014;39933.80033099874;39990;41939.161216776105;67047.32952670509;19463.513412164808;19620.509487262934;19636.90181549083;19638;19649.90175049144;13649.181049137083;14377.209253491159;14807.777883331542;14885.851141488663;14889.627759306104;20779.58440831205;20905.790942090687;20931.011553902354;21085.68371474429;21132.47168820761;7478;7614.200508946592;7657.195994420617;7680.423968202407;7705.961470192613;984.8276551603584;1087.9129669626411;1091.0834898684984;1091.317926296065;1163.959261425846;35066.64933350685;35265.29469410597;35283.294334113685;35636.821815890755;35687.82156089203;1209.371127013948;1212.6847019774923;1231.7290196156832;1232.6979889927024;1282.538286216961;17568.912155439397;18591.535211619546;18613.90693046553;18624.255029798656;18644.627107457774;671997;674976;678590;679327;683727;63041.684791575746;63457.682711586145;63459;63513.68243158755;63560.68219658872;1353.2218974089938;1430.8283006039342;1432.3482815319214;1467.985320146785;1489.2181604657426;33336.49995250122;35113.122171945906;35312;35791.46312805258;35877.46183807297;54029.189562157386;54268.72865635646;54488.18267725908;54593;54608.72695636496;433697;434223;434831;435445;523096;30580.84709576438;31346.843265783526;31351.21621959378;32040.83979580087;32487.350252995282;52682.473175267754;52753;52765.208521871435;52769;53486.19770703365;43404.78297608492;44070.559294406645;44328.335074974544;45156.548434515236;45525.31712024256;43180;43746;44920.55079449294;45405.31892021556;45504;44256.33615495834;44952.32571511495;45466.77266613778;45640.08719825518;45707;36205;38819.2236155281;39151.608483914795;39822.800885995384;40067.398989014604;30166.54750178793;30399.392012160075;30521;30656.252948098427;30903.381932361677 16:46:56 INFO - PROCESS | 2500 | |2;jquery.html;409.22860408130674;412.81629674794726;417.6450017485136;423.5129600958897;441.4261460101867;18997.430077097673;19616.509587260327;20517.281895133674;20609.896950515227;20658.793412065876;13557.779799818014;18990.24039038437;19080;19105.426837194907;19514.21943122277;5614.831555053355;5880.617759845596;5905.586608937376;5914.053751399777;5985.970070149643;5705.030144875361;5719.628224165416;5719.914201286984;5733.598648094635;5734.541236701075;5596.832095037155;5679.971600141994;5685.147227915804;5693.117566777136;5702.714864256771;5673.205751194836;5740.196372507852;5844.707764611752;5978.073398623221;5989.55078369124;4925.482824303468;5127.230915362688;5175.534201921808;5787.913181302284;5795.536357091422;13260;13401.597952061455;14142.575722728232;19536.51158721029;19919.203231870706;2364.021333120281;2399.8440101393353;2454.4109413740753;2547.3631592101974;2997.640283166012;9849.064338887794;9987.151092157175;10107.191424686082;10190.286679932407;11392.202545821796;1901.743264659281;1987.791281915407;2000.8099230573073;2069.9482512937147;2265.3996690876766;85766.57116714439;87763;87861;88822.55588722079;89102.55448722714;10728;10746.140308775279;10955.616553420592;11079.72300692489;11111.444427778579;14830.110193388431;14830.258487075604;14850.851491484947;14859.182744949132;14860.925695371454;8407.41148119638;8774.341924355698;8788.2529984952;8916.331275154389;8969.775755606162;8233.547154906537;8449.155084491502;8833.690820821368;8941.910580894239;9133.269338452908;4445.866624001285;4557.908841823211;4894.437139728977;4929.619706482191;5083.949160508421;1861.0973677766272;1903.1911437639226;1928.53715108373;1944.8444124470009;1948;976.99023009769;1016.5832008876325;1039.6621098142982;1064;1066.9519871605642;11822.645320640395;11827.586034488924;19842;20052.498687532927;21052;476.63299259569936;483.6952719786512;492.3623907040391;507.50264740553723;513.9100657384943;5258.8948221036135;6522.739090436329;6548.1814773153355;6624.470042396693;6675.432588229958;362498;628367;643357;685835;686017;80667;101429;104553.47723261335;107982.46008770063;111387;501.53357377639145;524.8766539863129;536.4421002157791;536.8201652446396;538.4534697282262;9938.850917236103;10585.206109541661;10784.514696838503;11249.831252531056;11377.943110284396;45018;45941.08117837692;46668.76665616786;46757.532424675315;48279.517204827505;59464.70267648634;59621.403785961586;60712;61485;62078.37921620907;8588;8746.169113934166;8795.384323097322;8842.91157088421;9087.273018158663;10983.890161098287;14049.22729249901;14117.788233176716;14149.858501414854;14573.562793116223;12315.630531084082;12388.504459821506;12577;12580.804823542116;12670;11613.3612651305;12155.453004614637;12681.93659031699;12768.74462510763;12904.935475322938;11734.061275098142;12212.75574488523;12308.138430309793;12331;12449.502019919102;8511.659533618833;8647.524386158822;8895.866562001705;8910.153535414125;8950.552472376225;7134.857302854018;11103.666889993314;12016.33910134951;12293.50825966987;12519.311437871005 16:46:56 INFO - PROCESS | 2500 | |3;mootools.html;1519.840416756241;1550.9922450387746;1667.9166041697913;1782.928682852686;1822.3348477805605;8622.482651040935;9247.95376023119;9266.53667316633;9269.53652317384;9275.582598783063;7939.722109726161;8575.185357391054;8787.736367908972;8862.291016718664;8967.41711788735;2408.446057406793;2454.9631755523683;2458.2502336787275;2459.483508463225;2460.9138680146198;2434.184548176363;2472.0359059966627;2495.650608914744;2503.9374015649573;2504.686914135733;2465.161844972709;2474.8762561871927;2482.8137889658256;2490.5267999080206;2493.887775050127;2390.796782273509;2402.4354276744953;2415.4565222824813;2433.7931275841574;2439.841410308324;2364.798992085675;2367.786899179082;2367.857928524294;2371.229350461107;2381.5951288281035;8927.151920567536;9024.639014439415;9025.548722563912;9027.232685221765;9067.954660226722;5358.624896257264;5505.311836020497;5505.889882202374;5506.559475241971;5507.889842203133;3315.4032274190636;3403.013126193401;3459.41189997701;3468.0289518934737;3506.2987402519343;1193.4390836306923;1226.4419689041465;1231.9815202771965;1245.2217364147407;1267.7274386006961;12485.313307768069;12515;12530.999999999909;12536;12542.74914501714;4702.6473014524045;4734.757266284658;4740.6681532292405;4811.663183577116;4920.655554111249;10659.360438373722;10664.200184986157;10676.679699609023;10680.305780124267;10691.893081069245;5476.2333273341765;5483.616146869681;5486.286782718301;5496.31296087989;5498.340199176125;4393.340998850134;4528.547145285445;4884.3161957326;4950.2079667253065;5046;3879.6508314251287;3956.5647778744315;4045.6763458923215;4047.332190188586;4050.6556942659613;1270.0474644016988;1273.719781648036;1274.5347947999098;1277.7444511097817;1298.2275546050084;984.8719666443317;1002.9699109026595;1018.4602160854758;1022.2026819081128;1031.3914790273625;5815.127730840408;5836.270466191726;5844.970775146097;5902.911456328158;6079.726412311456;476.3759475087646;1041.6614600254795;1098;1113.7215696075982;1142.600089968521;4158.293090174678;4249.851255206053;4294.527601963781;4297.67767417445;4396.252637051709;11098.889011109946;11347.319160850375;11423.885761142448;11449.828252576219;11470.827937580943;11036.779264414667;11241;11341.376224307742;11351.943240283746;11398.1451391146;8555.871661925075;8664.826703465895;8674.04585495595;8675.436096653697;8681.392302538761;6133.9079913802225;6170.81487555374;6198.659073750987;6280.371962803866;6282.811515654537;10337;10350.637727679496;10362.585496580052;10431.321964072311;10537.051665350155;10521.736956576147;10536.631217907336;10676.679699609023;10720.142388608736;10834.783304333712;7003.5797852129035;7559.735409260649;7718.691252349843;7760.18518055596;7845.450818442654;9013.278937684838;9080.545972701471;9087.31845111606;9091.272698183997;9091.681791137278;7841.490303130278;7882.172371900868;7887.526748395115;7933.048034235929;8231.670733170846;7725.072991241087;7750.496217745828;7776.183500732342;7902.328302094386;7906.209379062047;7223.8555228896175;7236.348728614239;7318.780436586911;7329.230430804689;7336.449766267658;5212.5569326607665;5279.234510995961;5284.075286824714;5309.33633295838;5331.09371406854;5265.368155821326;5328.733563321896;5330;5350.143976963667;5559.610827242054 16:46:56 INFO - PROCESS | 2500 | |4;prototype.html;340.3142667524938;349.5473361996214;350.59681366428606;358.19942428671914;358.67181528901057;5446.645968012076;5554.250176226208;5631.211630371751;5640.548756099518;5694.914576281359;5560.499555040045;5561.249231353766;5567.777288908449;5572.60991730579;5586.608937374375;4355.499117601476;4376.671749618776;4386.758728269944;4392.692511524195;4407.206702793496;4166.812458449343;4359.433273674434;4377.124575084979;4398.73607583546;4405.427294451732;4317.15815415994;4321.416608757809;4327.290720165548;4329.978350108245;4330.826766929319;4341.869743907688;4347.195768782785;4351.26028575143;4376.205755640172;4383.408239887606;3726.9813650931574;3825.445310429972;3846.557645870733;3850.191459793447;3854.2291541691493;5430.348358197002;5438.7280635968045;5439.700816455093;5450.182472629098;5452.345718513804;4970.826021089245;5017.422996355393;5020.723860187688;5028.874278143039;5031.119554078022;5528.336599608073;5799.942000580025;5810.622309549908;5822.767089316422;5847.473727364515;4197.202531519001;4201.894952626179;4214.262504061777;4227.661787057028;4236.216299984513;5885.558583106242;6007.309159446632;6028.39716028398;6048.60684055535;6069.726862291208;4404.273294906305;4418.823247070081;4444.400005999149;4449.9777501112285;4472.932906006413;4483.282674772021;4507.18870603288;4509.6843220974215;4518.751468669255;4519.367288579601;4869.878253043702;5038.5969122470115;5051.1413059779925;5060.1903695408555;5060.84817455477;5609.747561359749;5868.618539794899;5894.705264736746;5903.852403689943;5994.07091900756;1513.2736286582501;1536.5774412036556;1543.1589783567983;1553.324303927795;1559.2515592515429;990.2276224544867;1002.8746406699163;1005.0401716652557;1028.7685270814006;1030.1038096855634;4969.155243608523;4971.6271279654175;5000.399952005782;5010.924836127461;5015.724135172528;5249.343832020997;5251.111888461331;5254.97372513135;5276.445973172838;5288.3918349390315;4717.127331443694;4977.925331120036;5021.297018417425;5031.345925029723;5033.219850923113;2776.430831679501;2793.6368272124496;2806.7614252788335;2810.747032767061;2811.5642075478336;5396.784128634889;5412.810551630674;5450.400455949843;5474.178873168977;5477.287952566141;5295.205719142159;5354.9196762048605;5628.240187574625;6090.994985827378;6121.326654068049;6180.8763824723255;6190.690465476709;6193.938060619336;6207.224096987877;6219.129321895029;5889.705514724333;5957.815833017849;6014.278286605635;6036.456718895321;6038.215032045806;5866.325372582037;5897.705114744332;5902.9964905966945;5914.3198532167635;5922.348541660328;6179.938200617937;6227.906581401373;6246.531510136667;6255.24937007562;6260.874782504416;4133.2973394522405;4317.330813723752;4336.826526938887;4337.479502459725;4362.629176520031;5804.6807425592;5898.8230353089475;5992.61048031894;6015.105241848803;6032.638041717335;5626.746796394254;5815.156802263564;5838.737256823538;5862.912056319074;5867.6772777497645;5885.764569417175;5886.763030048554;5893.174955506318;5898.793542226002;5908.231929849092;5646.040173170489;5814.94185058144;5818.6217895836635;5820.708964551671;5831.0378787499585;5882.470577647861;5886.6468011917705;5897.70511474416;5911.261092363455;5918.881622367615;5494.148406996762;5766.855828604318;5782.335031471434;5785.450382213683;5791.333996590446 16:46:56 INFO - PROCESS | 2500 | |5;yui.html;774.9186335434779;815.7838172884183;828.8839562461254;881.9559022048896;888.6978427334705;13777.311134443287;14555.417783288669;15170.772438413418;15246.161461119635;15260.389584416624;14557.417703291881;14700.411983520673;14713.117212967229;14717.264136793145;14723;718.6370882704227;827.3381294964034;851.6337974670889;863.6286396849353;865.4071960706905;851.7700220940343;854.7692123126752;854.9487030778173;855.2345650642702;855.8630619100945;909.7998440343114;910.9271258299353;911.0206527982413;911.1890417528372;911.3893691226893;908.8727578139066;909.2998391238742;909.4815954905706;909.6588779207797;909.9818003639923;666.839958409983;667.1793693756706;667.546068673302;667.9165104361955;668.1180841289499;13868.098573592684;13877.444902203899;13888.513902013381;13894.23581703006;13897.235652039133;16308.184590770414;16377;16478;16796;17345.7398139028;12047.397630118547;12080.697982550419;12119.33343666098;12233.571824986082;12293.139480236387;3944.2703099926607;4043;4057.3711074783455;4069.409935559357;4171.874843754662;77837.61081194614;78025.60987195084;78124.60937695275;78827.6058619709;79853;11811.527538898348;11827.349495777642;11889.643310700692;12075.215111017756;12094.436559182699;22427.551448971255;22433.775662243494;22595.43511412228;22881.656775148385;22882.427939301317;8230.176982301842;9059.320550958704;9162;9304;9304.95347523258;4992.750362481861;5126.333576635088;5174.534291913746;5179.663321884065;5213.165893457029;3790.995386222653;3936.2717897189114;3938.7045971551674;3941.231459865299;3945;668.1247565688963;668.8194187569329;672.4586707700348;684.650828077674;691.9792406227821;663.3366633366634;738.841149152924;749.8050506868253;760.4866714967375;764.1059959846992;9848.704538863845;10150.340227885164;10202.438865862301;10427.895721042692;10738.94630526858;708.8121647763347;735.6983636708924;754.728297812787;788.4520258420342;789.5539020453425;5121.2574176744165;5272;5302.549283310962;5316.893662126813;5356.7321633918145;130742;136116;136291;139320;141634;117956;119209.80790192161;119633.2238404501;121947;122875;103829;106128;106517.46741266399;108693.45653271841;109185;9907.900920990985;10144;10181.847272290774;10183.287169898036;10253.179745620184;48306.5169348302;49634;54019.189712153566;59556.40443595508;60552.39447605644;50861.74569127131;67428.6628566854;78141.21858781339;79182.60408697919;81773;13690;13813.24027178515;13833.861661383256;13950.720985580434;14335.139891606119;15586.688266234838;15706.842931570538;15808.367665293259;15844.920775396049;15929.601759956095;14162.575122746333;14176.574702758933;14316.212608306643;14775;15053.698926021638;14116.364763585458;14356.138631682132;14383.208923509308;14388.568342949728;14774.63063423423;10411.635592754217;13197.142185758279;13250.337483125611;13557.932210338886;14935.626609334853;11801;12142.39288035577;12594.307313097868;12647.683807904876;12689.23864568129;11357.318560886373;11388.202825802115;11533.308001519936;11808.173427859967;11829.76340473203 16:46:56 INFO - PROCESS | 2500 | __end_tp_report 16:46:56 INFO - PROCESS | 2500 | __start_cc_report 16:46:56 INFO - PROCESS | 2500 | _x_x_mozilla_cycle_collect,940 16:46:56 INFO - PROCESS | 2500 | __end_cc_report 16:46:56 INFO - PROCESS | 2500 | __startTimestamp1478389616683__endTimestamp 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | ------- Summary: start ------- 16:46:56 INFO - PROCESS | 2500 | Number of tests: 6 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#0] dojo.html Cycles:165 Average:5621.40 Median:5821.39 stddev:643.85 (11.1%) stddev-sans-first:616.75 16:46:56 INFO - PROCESS | 2500 | Values: 3182.7 3186.1 3215.3 3226.2 3248.7 5525.9 5627.8 5630.9 5638.7 5651.7 5636.8 5727.1 5729.3 5734.7 5735.2 5169.8 5173.5 5181.4 5228.9 5237.6 4995.8 5047.0 5252.1 5253.9 5290.5 5049.5 5088.6 5125.4 5136.0 5174.5 5085.3 5128.5 5207.6 5210.9 5236.7 4947.0 5029.2 5106.8 5159.4 5176.8 5527.9 5542.1 5551.4 5552.2 5559.2 5979.8 6037.5 6096.2 6098.8 6104.4 5921.5 5985.6 6120.1 6129.9 6133.0 5571.5 5660.2 5678.7 5687.6 5713.3 6205.2 6230.2 6231.2 6235.3 6238.2 5713.5 5890.6 5940.2 5954.0 6005.1 6070.9 6088.9 6093.0 6112.1 6132.9 6022.7 6024.8 6035.0 6035.9 6041.6 5748.1 5870.6 5872.6 6036.6 6068.9 4589.6 4607.0 4607.1 4612.8 4616.2 4146.5 4148.0 4157.4 4168.9 4206.9 5790.3 5795.6 5805.1 5811.1 5821.4 5896.6 5996.5 6045.6 6112.0 6127.5 5800.1 5801.0 5828.4 5832.8 5835.8 5278.7 5282.0 5296.8 5297.6 5298.5 5972.9 6172.4 6300.1 6304.8 6314.6 6076.1 6127.9 6153.4 6154.8 6165.7 6261.6 6262.4 6274.2 6277.7 6325.4 5720.6 5859.6 6002.5 6003.5 6010.4 6002.4 6097.5 6101.9 6132.8 6139.9 5935.3 5970.4 5975.9 5978.0 5981.8 5747.5 5783.2 5924.8 5993.9 5994.1 5755.8 5839.1 5844.4 5871.9 5877.0 5878.7 5901.0 5901.8 5906.2 5941.0 5714.4 5757.9 5758.2 5763.9 5767.9 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#1] ext.html Cycles:180 Average:66887.15 Median:39517.80 stddev:137804.18 (348.7%) stddev-sans-first:138100.88 16:46:56 INFO - PROCESS | 2500 | Values: 598.2 640.0 663.5 671.5 679.7 34153.0 44779.6 45533.0 45710.5 45733.8 47683.0 48072.0 48114.8 48158.3 48262.5 8417.6 9094.0 9205.6 9246.7 9269.7 10785.2 11091.6 11095.8 11463.1 11465.2 11306.0 11389.1 11402.3 11407.7 11425.5 11148.9 11210.5 11231.1 11232.3 11233.4 11052.4 11083.3 11089.7 11094.7 11095.3 44984.6 45231.8 45331.0 45416.0 45423.0 3669.3 3819.9 4173.4 4267.7 4432.9 35541.8 35556.1 35655.1 35695.8 35938.0 4442.4 4446.3 4446.7 4544.2 4665.7 370352.0 371555.0 371904.0 372115.0 372835.0 63699.7 63989.0 64097.4 64160.0 64252.4 39212.8 39933.8 39990.0 41939.2 67047.3 19463.5 19620.5 19636.9 19638.0 19649.9 13649.2 14377.2 14807.8 14885.9 14889.6 20779.6 20905.8 20931.0 21085.7 21132.5 7478.0 7614.2 7657.2 7680.4 7706.0 984.8 1087.9 1091.1 1091.3 1164.0 35066.6 35265.3 35283.3 35636.8 35687.8 1209.4 1212.7 1231.7 1232.7 1282.5 17568.9 18591.5 18613.9 18624.3 18644.6 671997.0 674976.0 678590.0 679327.0 683727.0 63041.7 63457.7 63459.0 63513.7 63560.7 1353.2 1430.8 1432.3 1468.0 1489.2 33336.5 35113.1 35312.0 35791.5 35877.5 54029.2 54268.7 54488.2 54593.0 54608.7 433697.0 434223.0 434831.0 435445.0 523096.0 30580.8 31346.8 31351.2 32040.8 32487.4 52682.5 52753.0 52765.2 52769.0 53486.2 43404.8 44070.6 44328.3 45156.5 45525.3 43180.0 43746.0 44920.6 45405.3 45504.0 44256.3 44952.3 45466.8 45640.1 45707.0 36205.0 38819.2 39151.6 39822.8 40067.4 30166.5 30399.4 30521.0 30656.3 30903.4 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#2] jquery.html Cycles:180 Average:32245.09 Median:24731.93 stddev:101099.58 (408.8%) stddev-sans-first:101354.93 16:46:56 INFO - PROCESS | 2500 | Values: 409.2 412.8 417.6 423.5 441.4 18997.4 19616.5 20517.3 20609.9 20658.8 13557.8 18990.2 19080.0 19105.4 19514.2 5614.8 5880.6 5905.6 5914.1 5986.0 5705.0 5719.6 5719.9 5733.6 5734.5 5596.8 5680.0 5685.1 5693.1 5702.7 5673.2 5740.2 5844.7 5978.1 5989.6 4925.5 5127.2 5175.5 5787.9 5795.5 13260.0 13401.6 14142.6 19536.5 19919.2 2364.0 2399.8 2454.4 2547.4 2997.6 9849.1 9987.2 10107.2 10190.3 11392.2 1901.7 1987.8 2000.8 2069.9 2265.4 85766.6 87763.0 87861.0 88822.6 89102.6 10728.0 10746.1 10955.6 11079.7 11111.4 14830.1 14830.3 14850.9 14859.2 14860.9 8407.4 8774.3 8788.3 8916.3 8969.8 8233.5 8449.2 8833.7 8941.9 9133.3 4445.9 4557.9 4894.4 4929.6 5083.9 1861.1 1903.2 1928.5 1944.8 1948.0 977.0 1016.6 1039.7 1064.0 1067.0 11822.6 11827.6 19842.0 20052.5 21052.0 476.6 483.7 492.4 507.5 513.9 5258.9 6522.7 6548.2 6624.5 6675.4 362498.0 628367.0 643357.0 685835.0 686017.0 80667.0 101429.0 104553.5 107982.5 111387.0 501.5 524.9 536.4 536.8 538.5 9938.9 10585.2 10784.5 11249.8 11377.9 45018.0 45941.1 46668.8 46757.5 48279.5 59464.7 59621.4 60712.0 61485.0 62078.4 8588.0 8746.2 8795.4 8842.9 9087.3 10983.9 14049.2 14117.8 14149.9 14573.6 12315.6 12388.5 12577.0 12580.8 12670.0 11613.4 12155.5 12681.9 12768.7 12904.9 11734.1 12212.8 12308.1 12331.0 12449.5 8511.7 8647.5 8895.9 8910.2 8950.6 7134.9 11103.7 12016.3 12293.5 12519.3 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#3] mootools.html Cycles:180 Average:5982.31 Median:4296.10 stddev:3390.37 (78.9%) stddev-sans-first:3383.30 16:46:56 INFO - PROCESS | 2500 | Values: 1519.8 1551.0 1667.9 1782.9 1822.3 8622.5 9248.0 9266.5 9269.5 9275.6 7939.7 8575.2 8787.7 8862.3 8967.4 2408.4 2455.0 2458.3 2459.5 2460.9 2434.2 2472.0 2495.7 2503.9 2504.7 2465.2 2474.9 2482.8 2490.5 2493.9 2390.8 2402.4 2415.5 2433.8 2439.8 2364.8 2367.8 2367.9 2371.2 2381.6 8927.2 9024.6 9025.5 9027.2 9068.0 5358.6 5505.3 5505.9 5506.6 5507.9 3315.4 3403.0 3459.4 3468.0 3506.3 1193.4 1226.4 1232.0 1245.2 1267.7 12485.3 12515.0 12531.0 12536.0 12542.7 4702.6 4734.8 4740.7 4811.7 4920.7 10659.4 10664.2 10676.7 10680.3 10691.9 5476.2 5483.6 5486.3 5496.3 5498.3 4393.3 4528.5 4884.3 4950.2 5046.0 3879.7 3956.6 4045.7 4047.3 4050.7 1270.0 1273.7 1274.5 1277.7 1298.2 984.9 1003.0 1018.5 1022.2 1031.4 5815.1 5836.3 5845.0 5902.9 6079.7 476.4 1041.7 1098.0 1113.7 1142.6 4158.3 4249.9 4294.5 4297.7 4396.3 11098.9 11347.3 11423.9 11449.8 11470.8 11036.8 11241.0 11341.4 11351.9 11398.1 8555.9 8664.8 8674.0 8675.4 8681.4 6133.9 6170.8 6198.7 6280.4 6282.8 10337.0 10350.6 10362.6 10431.3 10537.1 10521.7 10536.6 10676.7 10720.1 10834.8 7003.6 7559.7 7718.7 7760.2 7845.5 9013.3 9080.5 9087.3 9091.3 9091.7 7841.5 7882.2 7887.5 7933.0 8231.7 7725.1 7750.5 7776.2 7902.3 7906.2 7223.9 7236.3 7318.8 7329.2 7336.4 5212.6 5279.2 5284.1 5309.3 5331.1 5265.4 5328.7 5330.0 5350.1 5559.6 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#4] prototype.html Cycles:180 Average:4843.98 Median:5375.85 stddev:1412.46 (26.3%) stddev-sans-first:1375.38 16:46:56 INFO - PROCESS | 2500 | Values: 340.3 349.5 350.6 358.2 358.7 5446.6 5554.3 5631.2 5640.5 5694.9 5560.5 5561.2 5567.8 5572.6 5586.6 4355.5 4376.7 4386.8 4392.7 4407.2 4166.8 4359.4 4377.1 4398.7 4405.4 4317.2 4321.4 4327.3 4330.0 4330.8 4341.9 4347.2 4351.3 4376.2 4383.4 3727.0 3825.4 3846.6 3850.2 3854.2 5430.3 5438.7 5439.7 5450.2 5452.3 4970.8 5017.4 5020.7 5028.9 5031.1 5528.3 5799.9 5810.6 5822.8 5847.5 4197.2 4201.9 4214.3 4227.7 4236.2 5885.6 6007.3 6028.4 6048.6 6069.7 4404.3 4418.8 4444.4 4450.0 4472.9 4483.3 4507.2 4509.7 4518.8 4519.4 4869.9 5038.6 5051.1 5060.2 5060.8 5609.7 5868.6 5894.7 5903.9 5994.1 1513.3 1536.6 1543.2 1553.3 1559.3 990.2 1002.9 1005.0 1028.8 1030.1 4969.2 4971.6 5000.4 5010.9 5015.7 5249.3 5251.1 5255.0 5276.4 5288.4 4717.1 4977.9 5021.3 5031.3 5033.2 2776.4 2793.6 2806.8 2810.7 2811.6 5396.8 5412.8 5450.4 5474.2 5477.3 5295.2 5354.9 5628.2 6091.0 6121.3 6180.9 6190.7 6193.9 6207.2 6219.1 5889.7 5957.8 6014.3 6036.5 6038.2 5866.3 5897.7 5903.0 5914.3 5922.3 6179.9 6227.9 6246.5 6255.2 6260.9 4133.3 4317.3 4336.8 4337.5 4362.6 5804.7 5898.8 5992.6 6015.1 6032.6 5626.7 5815.2 5838.7 5862.9 5867.7 5885.8 5886.8 5893.2 5898.8 5908.2 5646.0 5814.9 5818.6 5820.7 5831.0 5882.5 5886.6 5897.7 5911.3 5918.9 5494.1 5766.9 5782.3 5785.5 5791.3 16:46:56 INFO - PROCESS | 2500 | 16:46:56 INFO - PROCESS | 2500 | [#5] yui.html Cycles:180 Average:22939.19 Median:22738.55 stddev:34858.24 (153.3%) stddev-sans-first:34916.30 16:46:56 INFO - PROCESS | 2500 | Values: 774.9 815.8 828.9 882.0 888.7 13777.3 14555.4 15170.8 15246.2 15260.4 14557.4 14700.4 14713.1 14717.3 14723.0 718.6 827.3 851.6 863.6 865.4 851.8 854.8 854.9 855.2 855.9 909.8 910.9 911.0 911.2 911.4 908.9 909.3 909.5 909.7 910.0 666.8 667.2 667.5 667.9 668.1 13868.1 13877.4 13888.5 13894.2 13897.2 16308.2 16377.0 16478.0 16796.0 17345.7 12047.4 12080.7 12119.3 12233.6 12293.1 3944.3 4043.0 4057.4 4069.4 4171.9 77837.6 78025.6 78124.6 78827.6 79853.0 11811.5 11827.3 11889.6 12075.2 12094.4 22427.6 22433.8 22595.4 22881.7 22882.4 8230.2 9059.3 9162.0 9304.0 9305.0 4992.8 5126.3 5174.5 5179.7 5213.2 3791.0 3936.3 3938.7 3941.2 3945.0 668.1 668.8 672.5 684.7 692.0 663.3 738.8 749.8 760.5 764.1 9848.7 10150.3 10202.4 10427.9 10738.9 708.8 735.7 754.7 788.5 789.6 5121.3 5272.0 5302.5 5316.9 5356.7 130742.0 136116.0 136291.0 139320.0 141634.0 117956.0 119209.8 119633.2 121947.0 122875.0 103829.0 106128.0 106517.5 108693.5 109185.0 9907.9 10144.0 10181.8 10183.3 10253.2 48306.5 49634.0 54019.2 59556.4 60552.4 50861.7 67428.7 78141.2 79182.6 81773.0 13690.0 13813.2 13833.9 13950.7 14335.1 15586.7 15706.8 15808.4 15844.9 15929.6 14162.6 14176.6 14316.2 14775.0 15053.7 14116.4 14356.1 14383.2 14388.6 14774.6 10411.6 13197.1 13250.3 13557.9 14935.6 11801.0 12142.4 12594.3 12647.7 12689.2 11357.3 11388.2 11533.3 11808.2 11829.8 16:46:56 INFO - PROCESS | 2500 | -------- Summary: end -------- 16:46:56 INFO - PROCESS | 2500 | 16:46:57 INFO - TEST-INFO | 2500: exit 0 16:46:57 INFO - TEST-OK | dromaeo_css | took 1112913ms 16:46:57 INFO - TEST-START | kraken 16:46:57 INFO - operating with platform_type : linux_ 16:46:57 INFO - Initialising browser for kraken test... 16:46:57 INFO - TEST-INFO | started process 2754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_737Kj/profile http://localhost:42450/getInfo.html) 16:47:01 INFO - PROCESS | 2754 | __metrics Screen width/height:1600/1200 16:47:01 INFO - PROCESS | 2754 | colorDepth:24 16:47:01 INFO - PROCESS | 2754 | Browser inner width/height: 1024/697 16:47:01 INFO - PROCESS | 2754 | __metrics 16:47:01 INFO - PROCESS | 2754 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:47:01 INFO - PROCESS | 2754 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:02 INFO - PROCESS | 2754 | console.error: PushService: 16:47:02 INFO - PROCESS | 2754 | stateChangeProcessEnqueue: Error transitioning state 16:47:02 INFO - PROCESS | 2754 | UnknownError 16:47:02 INFO - TEST-INFO | 2754: exit 0 16:47:02 INFO - Browser initialized. 16:47:02 INFO - Running cycle 1/1 for kraken test... 16:47:02 INFO - TEST-INFO | started process 2902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_737Kj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:14 INFO - PROCESS | 2902 | RSS: Main: 144797696 16:47:14 INFO - PROCESS | 2902 | 16:47:17 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42450/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:17 INFO - PROCESS | 2902 | RSS: Main: 199753728 16:47:17 INFO - PROCESS | 2902 | 16:47:20 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42450/tests/kraken/driver.html?testName=audio-dft) 16:47:21 INFO - PROCESS | 2902 | RSS: Main: 184299520 16:47:21 INFO - PROCESS | 2902 | 16:47:23 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42450/tests/kraken/driver.html?testName=audio-fft) 16:47:24 INFO - PROCESS | 2902 | RSS: Main: 275439616 16:47:24 INFO - PROCESS | 2902 | 16:47:26 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42450/tests/kraken/driver.html?testName=audio-oscillator) 16:47:27 INFO - PROCESS | 2902 | RSS: Main: 180862976 16:47:27 INFO - PROCESS | 2902 | 16:47:29 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42450/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:29 INFO - PROCESS | 2902 | RSS: Main: 179736576 16:47:29 INFO - PROCESS | 2902 | 16:47:32 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42450/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:33 INFO - PROCESS | 2902 | RSS: Main: 215773184 16:47:33 INFO - PROCESS | 2902 | 16:47:36 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42450/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:37 INFO - PROCESS | 2902 | RSS: Main: 214716416 16:47:37 INFO - PROCESS | 2902 | 16:47:40 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42450/tests/kraken/driver.html?testName=json-parse-financial) 16:47:40 INFO - PROCESS | 2902 | RSS: Main: 214548480 16:47:40 INFO - PROCESS | 2902 | 16:47:42 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42450/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:42 INFO - PROCESS | 2902 | RSS: Main: 177057792 16:47:42 INFO - PROCESS | 2902 | 16:47:44 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:45 INFO - PROCESS | 2902 | RSS: Main: 184123392 16:47:45 INFO - PROCESS | 2902 | 16:47:47 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:47 INFO - PROCESS | 2902 | RSS: Main: 190881792 16:47:47 INFO - PROCESS | 2902 | 16:47:50 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:50 INFO - PROCESS | 2902 | RSS: Main: 197857280 16:47:50 INFO - PROCESS | 2902 | 16:47:53 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:54 INFO - PROCESS | 2902 | RSS: Main: 194899968 16:47:54 INFO - PROCESS | 2902 | 16:47:55 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:42450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42450/tests/kraken/driver.html?testName=ai-astar) 16:47:56 INFO - PROCESS | 2902 | RSS: Main: 245886976 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | __start_tp_report 16:47:56 INFO - PROCESS | 2902 | _x_x_mozilla_page_load 16:47:56 INFO - PROCESS | 2902 | _x_x_mozilla_page_load_details 16:47:56 INFO - PROCESS | 2902 | |i|pagename|runs| 16:47:56 INFO - PROCESS | 2902 | |0;ai-astar;109;110;120;111;109;109;108;110;110;111 16:47:56 INFO - PROCESS | 2902 | |1;audio-beat-detection;145;116;141;164;119;146;148;117;176;122 16:47:56 INFO - PROCESS | 2902 | |2;audio-dft;159;160;160;158;159;159;160;159;160;160 16:47:56 INFO - PROCESS | 2902 | |3;audio-fft;74;74;75;179;75;76;74;74;170;76 16:47:56 INFO - PROCESS | 2902 | |4;audio-oscillator;83;83;146;87;83;83;143;83;85;141 16:47:56 INFO - PROCESS | 2902 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;116;117;117 16:47:56 INFO - PROCESS | 2902 | |6;imaging-darkroom;146;146;145;146;147;146;146;145;145;145 16:47:56 INFO - PROCESS | 2902 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 16:47:56 INFO - PROCESS | 2902 | |8;json-parse-financial;54;53;53;54;53;52;53;52;56;52 16:47:56 INFO - PROCESS | 2902 | |9;json-stringify-tinderbox;60;59;59;60;60;59;60;60;61;60 16:47:56 INFO - PROCESS | 2902 | |10;stanford-crypto-aes;74;72;73;72;77;78;73;73;72;71 16:47:56 INFO - PROCESS | 2902 | |11;stanford-crypto-ccm;122;128;116;120;127;127;120;116;151;127 16:47:56 INFO - PROCESS | 2902 | |12;stanford-crypto-pbkdf2;155;156;156;150;151;158;152;151;152;154 16:47:56 INFO - PROCESS | 2902 | |13;stanford-crypto-sha256-iterative;61;61;61;61;60;60;64;60;59;61 16:47:56 INFO - PROCESS | 2902 | __end_tp_report 16:47:56 INFO - PROCESS | 2902 | __start_cc_report 16:47:56 INFO - PROCESS | 2902 | _x_x_mozilla_cycle_collect,1702 16:47:56 INFO - PROCESS | 2902 | __end_cc_report 16:47:56 INFO - PROCESS | 2902 | __startTimestamp1478389676011__endTimestamp 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | ------- Summary: start ------- 16:47:56 INFO - PROCESS | 2902 | Number of tests: 14 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#0] ai-astar Cycles:10 Average:110.70 Median:110.00 stddev:3.40 (3.1%) stddev-sans-first:3.55 16:47:56 INFO - PROCESS | 2902 | Values: 109.0 110.0 120.0 111.0 109.0 109.0 108.0 110.0 110.0 111.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:145.50 stddev:20.72 (14.2%) stddev-sans-first:21.88 16:47:56 INFO - PROCESS | 2902 | Values: 145.0 116.0 141.0 164.0 119.0 146.0 148.0 117.0 176.0 122.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#2] audio-dft Cycles:10 Average:159.40 Median:160.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 16:47:56 INFO - PROCESS | 2902 | Values: 159.0 160.0 160.0 158.0 159.0 159.0 160.0 159.0 160.0 160.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#3] audio-fft Cycles:10 Average:94.70 Median:74.50 stddev:42.12 (56.5%) stddev-sans-first:44.00 16:47:56 INFO - PROCESS | 2902 | Values: 74.0 74.0 75.0 179.0 75.0 76.0 74.0 74.0 170.0 76.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:83.00 stddev:28.78 (34.7%) stddev-sans-first:29.72 16:47:56 INFO - PROCESS | 2902 | Values: 83.0 83.0 146.0 87.0 83.0 83.0 143.0 83.0 85.0 141.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:47:56 INFO - PROCESS | 2902 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 16:47:56 INFO - PROCESS | 2902 | Values: 146.0 146.0 145.0 146.0 147.0 146.0 146.0 145.0 145.0 145.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:47:56 INFO - PROCESS | 2902 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.23 (2.3%) stddev-sans-first:1.27 16:47:56 INFO - PROCESS | 2902 | Values: 54.0 53.0 53.0 54.0 53.0 52.0 53.0 52.0 56.0 52.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 16:47:56 INFO - PROCESS | 2902 | Values: 60.0 59.0 59.0 60.0 60.0 59.0 60.0 60.0 61.0 60.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.00 stddev:2.27 (3.1%) stddev-sans-first:2.40 16:47:56 INFO - PROCESS | 2902 | Values: 74.0 72.0 73.0 72.0 77.0 78.0 73.0 73.0 72.0 71.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#11] stanford-crypto-ccm Cycles:10 Average:125.40 Median:127.00 stddev:10.09 (7.9%) stddev-sans-first:10.63 16:47:56 INFO - PROCESS | 2902 | Values: 122.0 128.0 116.0 120.0 127.0 127.0 120.0 116.0 151.0 127.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.50 stddev:2.68 (1.7%) stddev-sans-first:2.78 16:47:56 INFO - PROCESS | 2902 | Values: 155.0 156.0 156.0 150.0 151.0 158.0 152.0 151.0 152.0 154.0 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - PROCESS | 2902 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.00 stddev:1.32 (2.2%) stddev-sans-first:1.39 16:47:56 INFO - PROCESS | 2902 | Values: 61.0 61.0 61.0 61.0 60.0 60.0 64.0 60.0 59.0 61.0 16:47:56 INFO - PROCESS | 2902 | -------- Summary: end -------- 16:47:56 INFO - PROCESS | 2902 | 16:47:56 INFO - TEST-INFO | 2902: exit 0 16:47:57 INFO - TEST-OK | kraken | took 59588ms 16:47:57 INFO - SUITE-END | took 1172s 16:47:57 INFO - Completed test suite (00:19:32) 16:47:57 INFO - javascript benchmark 16:47:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.729467995221, 3186.0601122668813, 3215.340855124701, 3226.2095786532273, 3248.707616314531, 5525.91711124333, 5627.831165065049, 5630.915536266949, 5638.689872057045, 5651.717414129293, 5636.802711905084, 5727.097823006831, 5729.255196824417, 5734.741936612852, 5735.168400581913, 5169.819056333029, 5173.534381905628, 5181.352330958621, 5228.895422091556, 5237.580993520528, 4995.800167993276, 5046.97476512617, 5252.107141785887, 5253.8949221015555, 5290.523852853243, 5049.4698056704065, 5088.618353623475, 5125.384953805548, 5135.972805438909, 5174.534291913727, 5085.288059671649, 5128.512791284822, 5207.557357624606, 5210.895782084375, 5236.73816309183, 4946.975265123651, 5029.152698185891, 5106.846794596168, 5159.406668233126, 5176.844694659128, 5527.944720552783, 5542.085555883315, 5551.361593416768, 5552.194931734876, 5559.193916882029, 5979.790707325223, 6037.54718396122, 6096.1770161028135, 6098.847528811771, 6104.3590423005835, 5921.467067963904, 5985.640861548278, 6120.081987701836, 6129.938700612981, 6133.0, 5571.470710282517, 5660.235868157828, 5678.659280443187, 5687.573431992617, 5713.3429655589725, 6205.217322026895, 6230.158928544589, 6231.158793562901, 6235.314115447297, 6238.220222472191, 5713.457221563945, 5890.558208134321, 5940.198073260054, 5954.047352423591, 6005.069214271794, 6070.91887144588, 6088.908666370008, 6093.025115981422, 6112.144299798032, 6132.877342453126, 6022.668753218528, 6024.759009639565, 6035.034394496859, 6035.939640603538, 6041.577089603774, 5748.051571490741, 5870.559708021916, 5872.64764114146, 6036.607620504653, 6068.878622427526, 4589.609883159968, 4607.0, 4607.101615185007, 4612.7924243409125, 4616.215243408628, 4146.460960075231, 4147.958520414818, 4157.355609880473, 4168.937465938014, 4206.894827629272, 5790.334111577138, 5795.565332600072, 5805.10020946754, 5811.128330750421, 5821.388754180834, 5896.616719913192, 5996.4603185713495, 6045.576809623372, 6111.9694401528595, 6127.509799216052, 5800.071988481823, 5800.970995144998, 5828.358880523139, 5832.825015249549, 5835.766569337264, 5278.656887302313, 5282.0, 5296.814611488579, 5297.576193904479, 5298.523132918056, 5972.85067873298, 6172.444479996732, 6300.1494798202575, 6304.810855674337, 6314.589551679126, 6076.058210977306, 6127.930662619259, 6153.384661533811, 6154.815355539341, 6165.722542485689, 6261.624302541862, 6262.405071518198, 6274.152989346562, 6277.74889004453, 6325.399087086719, 5720.628159169641, 5859.619124756877, 6002.489788367863, 6003.519718422428, 6010.368911264254, 6002.429769171922, 6097.512199023979, 6101.908471373022, 6132.754689812357, 6139.938600613937, 5935.317438494456, 5970.402959704169, 5975.880482390415, 5978.043513037893, 5981.7906373278465, 5747.48272655463, 5783.161441590862, 5924.763009479745, 5993.850153746192, 5994.07091900756, 5755.827325180251, 5839.065749480063, 5844.415558444121, 5871.882562348815, 5877.0302900021015, 5878.676672783038, 5901.0, 5901.763929442946, 5906.2026626406605, 5941.0197317442135, 5714.399988001366, 5757.942420575908, 5758.1650660654805, 5763.94236057634, 5767.884642307215], "value": 5577.247823593931, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2491972574421, 639.974401023959, 663.4924282923564, 671.5232185148544, 679.6601699150425, 34153.0, 44779.55220447794, 45533.0, 45710.542894571045, 45733.771331143296, 47683.0463390732, 48072.0, 48114.75942620291, 48158.27762583555, 48262.51737482624, 8417.621207045668, 9093.95453022734, 9205.585748641293, 9246.67636632718, 9269.72190834276, 10785.245032847704, 11091.611793587226, 11095.83356249657, 11463.140264480156, 11465.197436179471, 11306.0, 11389.088872890148, 11402.258853174559, 11407.657770266906, 11425.542978280859, 11148.944255278753, 11210.495527701192, 11231.10151187903, 11232.269902456314, 11233.382163980978, 11052.392118433483, 11083.279586826831, 11089.72275693106, 11094.722631934266, 11095.278806877526, 44984.55015449869, 45231.77384113058, 45331.0, 45416.0, 45423.0, 3669.284489524544, 3819.866304679323, 4173.353130264813, 4267.679924005712, 4432.867013989586, 35541.82229088838, 35556.111097222514, 35655.10862228439, 35695.82152089249, 35938.0, 4442.400275962736, 4446.288593824973, 4446.710963787344, 4544.182047231497, 4665.650076244296, 370352.0, 371555.0, 371904.0, 372115.0, 372835.0, 63699.6815015922, 63989.0, 64097.35902640914, 64160.0, 64252.35747642464, 39212.80393598014, 39933.80033099874, 39990.0, 41939.161216776105, 67047.32952670509, 19463.513412164808, 19620.509487262934, 19636.90181549083, 19638.0, 19649.90175049144, 13649.181049137083, 14377.209253491159, 14807.777883331542, 14885.851141488663, 14889.627759306104, 20779.58440831205, 20905.790942090687, 20931.011553902354, 21085.68371474429, 21132.47168820761, 7478.0, 7614.200508946592, 7657.195994420617, 7680.423968202407, 7705.961470192613, 984.8276551603584, 1087.9129669626411, 1091.0834898684984, 1091.317926296065, 1163.959261425846, 35066.64933350685, 35265.29469410597, 35283.294334113685, 35636.821815890755, 35687.82156089203, 1209.371127013948, 1212.6847019774923, 1231.7290196156832, 1232.6979889927024, 1282.538286216961, 17568.912155439397, 18591.535211619546, 18613.90693046553, 18624.255029798656, 18644.627107457774, 671997.0, 674976.0, 678590.0, 679327.0, 683727.0, 63041.684791575746, 63457.682711586145, 63459.0, 63513.68243158755, 63560.68219658872, 1353.2218974089938, 1430.8283006039342, 1432.3482815319214, 1467.985320146785, 1489.2181604657426, 33336.49995250122, 35113.122171945906, 35312.0, 35791.46312805258, 35877.46183807297, 54029.189562157386, 54268.72865635646, 54488.18267725908, 54593.0, 54608.72695636496, 433697.0, 434223.0, 434831.0, 435445.0, 523096.0, 30580.84709576438, 31346.843265783526, 31351.21621959378, 32040.83979580087, 32487.350252995282, 52682.473175267754, 52753.0, 52765.208521871435, 52769.0, 53486.19770703365, 43404.78297608492, 44070.559294406645, 44328.335074974544, 45156.548434515236, 45525.31712024256, 43180.0, 43746.0, 44920.55079449294, 45405.31892021556, 45504.0, 44256.33615495834, 44952.32571511495, 45466.77266613778, 45640.08719825518, 45707.0, 36205.0, 38819.2236155281, 39151.608483914795, 39822.800885995384, 40067.398989014604, 30166.54750178793, 30399.392012160075, 30521.0, 30656.252948098427, 30903.381932361677], "value": 22058.651478186843, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.22860408130674, 412.81629674794726, 417.6450017485136, 423.5129600958897, 441.4261460101867, 18997.430077097673, 19616.509587260327, 20517.281895133674, 20609.896950515227, 20658.793412065876, 13557.779799818014, 18990.24039038437, 19080.0, 19105.426837194907, 19514.21943122277, 5614.831555053355, 5880.617759845596, 5905.586608937376, 5914.053751399777, 5985.970070149643, 5705.030144875361, 5719.628224165416, 5719.914201286984, 5733.598648094635, 5734.541236701075, 5596.832095037155, 5679.971600141994, 5685.147227915804, 5693.117566777136, 5702.714864256771, 5673.205751194836, 5740.196372507852, 5844.707764611752, 5978.073398623221, 5989.55078369124, 4925.482824303468, 5127.230915362688, 5175.534201921808, 5787.913181302284, 5795.536357091422, 13260.0, 13401.597952061455, 14142.575722728232, 19536.51158721029, 19919.203231870706, 2364.021333120281, 2399.8440101393353, 2454.4109413740753, 2547.3631592101974, 2997.640283166012, 9849.064338887794, 9987.151092157175, 10107.191424686082, 10190.286679932407, 11392.202545821796, 1901.743264659281, 1987.791281915407, 2000.8099230573073, 2069.9482512937147, 2265.3996690876766, 85766.57116714439, 87763.0, 87861.0, 88822.55588722079, 89102.55448722714, 10728.0, 10746.140308775279, 10955.616553420592, 11079.72300692489, 11111.444427778579, 14830.110193388431, 14830.258487075604, 14850.851491484947, 14859.182744949132, 14860.925695371454, 8407.41148119638, 8774.341924355698, 8788.2529984952, 8916.331275154389, 8969.775755606162, 8233.547154906537, 8449.155084491502, 8833.690820821368, 8941.910580894239, 9133.269338452908, 4445.866624001285, 4557.908841823211, 4894.437139728977, 4929.619706482191, 5083.949160508421, 1861.0973677766272, 1903.1911437639226, 1928.53715108373, 1944.8444124470009, 1948.0, 976.99023009769, 1016.5832008876325, 1039.6621098142982, 1064.0, 1066.9519871605642, 11822.645320640395, 11827.586034488924, 19842.0, 20052.498687532927, 21052.0, 476.63299259569936, 483.6952719786512, 492.3623907040391, 507.50264740553723, 513.9100657384943, 5258.8948221036135, 6522.739090436329, 6548.1814773153355, 6624.470042396693, 6675.432588229958, 362498.0, 628367.0, 643357.0, 685835.0, 686017.0, 80667.0, 101429.0, 104553.47723261335, 107982.46008770063, 111387.0, 501.53357377639145, 524.8766539863129, 536.4421002157791, 536.8201652446396, 538.4534697282262, 9938.850917236103, 10585.206109541661, 10784.514696838503, 11249.831252531056, 11377.943110284396, 45018.0, 45941.08117837692, 46668.76665616786, 46757.532424675315, 48279.517204827505, 59464.70267648634, 59621.403785961586, 60712.0, 61485.0, 62078.37921620907, 8588.0, 8746.169113934166, 8795.384323097322, 8842.91157088421, 9087.273018158663, 10983.890161098287, 14049.22729249901, 14117.788233176716, 14149.858501414854, 14573.562793116223, 12315.630531084082, 12388.504459821506, 12577.0, 12580.804823542116, 12670.0, 11613.3612651305, 12155.453004614637, 12681.93659031699, 12768.74462510763, 12904.935475322938, 11734.061275098142, 12212.75574488523, 12308.138430309793, 12331.0, 12449.502019919102, 8511.659533618833, 8647.524386158822, 8895.866562001705, 8910.153535414125, 8950.552472376225, 7134.857302854018, 11103.666889993314, 12016.33910134951, 12293.50825966987, 12519.311437871005], "value": 8780.944097641575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.840416756241, 1550.9922450387746, 1667.9166041697913, 1782.928682852686, 1822.3348477805605, 8622.482651040935, 9247.95376023119, 9266.53667316633, 9269.53652317384, 9275.582598783063, 7939.722109726161, 8575.185357391054, 8787.736367908972, 8862.291016718664, 8967.41711788735, 2408.446057406793, 2454.9631755523683, 2458.2502336787275, 2459.483508463225, 2460.9138680146198, 2434.184548176363, 2472.0359059966627, 2495.650608914744, 2503.9374015649573, 2504.686914135733, 2465.161844972709, 2474.8762561871927, 2482.8137889658256, 2490.5267999080206, 2493.887775050127, 2390.796782273509, 2402.4354276744953, 2415.4565222824813, 2433.7931275841574, 2439.841410308324, 2364.798992085675, 2367.786899179082, 2367.857928524294, 2371.229350461107, 2381.5951288281035, 8927.151920567536, 9024.639014439415, 9025.548722563912, 9027.232685221765, 9067.954660226722, 5358.624896257264, 5505.311836020497, 5505.889882202374, 5506.559475241971, 5507.889842203133, 3315.4032274190636, 3403.013126193401, 3459.41189997701, 3468.0289518934737, 3506.2987402519343, 1193.4390836306923, 1226.4419689041465, 1231.9815202771965, 1245.2217364147407, 1267.7274386006961, 12485.313307768069, 12515.0, 12530.999999999909, 12536.0, 12542.74914501714, 4702.6473014524045, 4734.757266284658, 4740.6681532292405, 4811.663183577116, 4920.655554111249, 10659.360438373722, 10664.200184986157, 10676.679699609023, 10680.305780124267, 10691.893081069245, 5476.2333273341765, 5483.616146869681, 5486.286782718301, 5496.31296087989, 5498.340199176125, 4393.340998850134, 4528.547145285445, 4884.3161957326, 4950.2079667253065, 5046.0, 3879.6508314251287, 3956.5647778744315, 4045.6763458923215, 4047.332190188586, 4050.6556942659613, 1270.0474644016988, 1273.719781648036, 1274.5347947999098, 1277.7444511097817, 1298.2275546050084, 984.8719666443317, 1002.9699109026595, 1018.4602160854758, 1022.2026819081128, 1031.3914790273625, 5815.127730840408, 5836.270466191726, 5844.970775146097, 5902.911456328158, 6079.726412311456, 476.3759475087646, 1041.6614600254795, 1098.0, 1113.7215696075982, 1142.600089968521, 4158.293090174678, 4249.851255206053, 4294.527601963781, 4297.67767417445, 4396.252637051709, 11098.889011109946, 11347.319160850375, 11423.885761142448, 11449.828252576219, 11470.827937580943, 11036.779264414667, 11241.0, 11341.376224307742, 11351.943240283746, 11398.1451391146, 8555.871661925075, 8664.826703465895, 8674.04585495595, 8675.436096653697, 8681.392302538761, 6133.9079913802225, 6170.81487555374, 6198.659073750987, 6280.371962803866, 6282.811515654537, 10337.0, 10350.637727679496, 10362.585496580052, 10431.321964072311, 10537.051665350155, 10521.736956576147, 10536.631217907336, 10676.679699609023, 10720.142388608736, 10834.783304333712, 7003.5797852129035, 7559.735409260649, 7718.691252349843, 7760.18518055596, 7845.450818442654, 9013.278937684838, 9080.545972701471, 9087.31845111606, 9091.272698183997, 9091.681791137278, 7841.490303130278, 7882.172371900868, 7887.526748395115, 7933.048034235929, 8231.670733170846, 7725.072991241087, 7750.496217745828, 7776.183500732342, 7902.328302094386, 7906.209379062047, 7223.8555228896175, 7236.348728614239, 7318.780436586911, 7329.230430804689, 7336.449766267658, 5212.5569326607665, 5279.234510995961, 5284.075286824714, 5309.33633295838, 5331.09371406854, 5265.368155821326, 5328.733563321896, 5330.0, 5350.143976963667, 5559.610827242054], "value": 4802.239063840083, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.3142667524938, 349.5473361996214, 350.59681366428606, 358.19942428671914, 358.67181528901057, 5446.645968012076, 5554.250176226208, 5631.211630371751, 5640.548756099518, 5694.914576281359, 5560.499555040045, 5561.249231353766, 5567.777288908449, 5572.60991730579, 5586.608937374375, 4355.499117601476, 4376.671749618776, 4386.758728269944, 4392.692511524195, 4407.206702793496, 4166.812458449343, 4359.433273674434, 4377.124575084979, 4398.73607583546, 4405.427294451732, 4317.15815415994, 4321.416608757809, 4327.290720165548, 4329.978350108245, 4330.826766929319, 4341.869743907688, 4347.195768782785, 4351.26028575143, 4376.205755640172, 4383.408239887606, 3726.9813650931574, 3825.445310429972, 3846.557645870733, 3850.191459793447, 3854.2291541691493, 5430.348358197002, 5438.7280635968045, 5439.700816455093, 5450.182472629098, 5452.345718513804, 4970.826021089245, 5017.422996355393, 5020.723860187688, 5028.874278143039, 5031.119554078022, 5528.336599608073, 5799.942000580025, 5810.622309549908, 5822.767089316422, 5847.473727364515, 4197.202531519001, 4201.894952626179, 4214.262504061777, 4227.661787057028, 4236.216299984513, 5885.558583106242, 6007.309159446632, 6028.39716028398, 6048.60684055535, 6069.726862291208, 4404.273294906305, 4418.823247070081, 4444.400005999149, 4449.9777501112285, 4472.932906006413, 4483.282674772021, 4507.18870603288, 4509.6843220974215, 4518.751468669255, 4519.367288579601, 4869.878253043702, 5038.5969122470115, 5051.1413059779925, 5060.1903695408555, 5060.84817455477, 5609.747561359749, 5868.618539794899, 5894.705264736746, 5903.852403689943, 5994.07091900756, 1513.2736286582501, 1536.5774412036556, 1543.1589783567983, 1553.324303927795, 1559.2515592515429, 990.2276224544867, 1002.8746406699163, 1005.0401716652557, 1028.7685270814006, 1030.1038096855634, 4969.155243608523, 4971.6271279654175, 5000.399952005782, 5010.924836127461, 5015.724135172528, 5249.343832020997, 5251.111888461331, 5254.97372513135, 5276.445973172838, 5288.3918349390315, 4717.127331443694, 4977.925331120036, 5021.297018417425, 5031.345925029723, 5033.219850923113, 2776.430831679501, 2793.6368272124496, 2806.7614252788335, 2810.747032767061, 2811.5642075478336, 5396.784128634889, 5412.810551630674, 5450.400455949843, 5474.178873168977, 5477.287952566141, 5295.205719142159, 5354.9196762048605, 5628.240187574625, 6090.994985827378, 6121.326654068049, 6180.8763824723255, 6190.690465476709, 6193.938060619336, 6207.224096987877, 6219.129321895029, 5889.705514724333, 5957.815833017849, 6014.278286605635, 6036.456718895321, 6038.215032045806, 5866.325372582037, 5897.705114744332, 5902.9964905966945, 5914.3198532167635, 5922.348541660328, 6179.938200617937, 6227.906581401373, 6246.531510136667, 6255.24937007562, 6260.874782504416, 4133.2973394522405, 4317.330813723752, 4336.826526938887, 4337.479502459725, 4362.629176520031, 5804.6807425592, 5898.8230353089475, 5992.61048031894, 6015.105241848803, 6032.638041717335, 5626.746796394254, 5815.156802263564, 5838.737256823538, 5862.912056319074, 5867.6772777497645, 5885.764569417175, 5886.763030048554, 5893.174955506318, 5898.793542226002, 5908.231929849092, 5646.040173170489, 5814.94185058144, 5818.6217895836635, 5820.708964551671, 5831.0378787499585, 5882.470577647861, 5886.6468011917705, 5897.70511474416, 5911.261092363455, 5918.881622367615, 5494.148406996762, 5766.855828604318, 5782.335031471434, 5785.450382213683, 5791.333996590446], "value": 4399.043503589293, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.9186335434779, 815.7838172884183, 828.8839562461254, 881.9559022048896, 888.6978427334705, 13777.311134443287, 14555.417783288669, 15170.772438413418, 15246.161461119635, 15260.389584416624, 14557.417703291881, 14700.411983520673, 14713.117212967229, 14717.264136793145, 14723.0, 718.6370882704227, 827.3381294964034, 851.6337974670889, 863.6286396849353, 865.4071960706905, 851.7700220940343, 854.7692123126752, 854.9487030778173, 855.2345650642702, 855.8630619100945, 909.7998440343114, 910.9271258299353, 911.0206527982413, 911.1890417528372, 911.3893691226893, 908.8727578139066, 909.2998391238742, 909.4815954905706, 909.6588779207797, 909.9818003639923, 666.839958409983, 667.1793693756706, 667.546068673302, 667.9165104361955, 668.1180841289499, 13868.098573592684, 13877.444902203899, 13888.513902013381, 13894.23581703006, 13897.235652039133, 16308.184590770414, 16377.0, 16478.0, 16796.0, 17345.7398139028, 12047.397630118547, 12080.697982550419, 12119.33343666098, 12233.571824986082, 12293.139480236387, 3944.2703099926607, 4043.0, 4057.3711074783455, 4069.409935559357, 4171.874843754662, 77837.61081194614, 78025.60987195084, 78124.60937695275, 78827.6058619709, 79853.0, 11811.527538898348, 11827.349495777642, 11889.643310700692, 12075.215111017756, 12094.436559182699, 22427.551448971255, 22433.775662243494, 22595.43511412228, 22881.656775148385, 22882.427939301317, 8230.176982301842, 9059.320550958704, 9162.0, 9304.0, 9304.95347523258, 4992.750362481861, 5126.333576635088, 5174.534291913746, 5179.663321884065, 5213.165893457029, 3790.995386222653, 3936.2717897189114, 3938.7045971551674, 3941.231459865299, 3945.0, 668.1247565688963, 668.8194187569329, 672.4586707700348, 684.650828077674, 691.9792406227821, 663.3366633366634, 738.841149152924, 749.8050506868253, 760.4866714967375, 764.1059959846992, 9848.704538863845, 10150.340227885164, 10202.438865862301, 10427.895721042692, 10738.94630526858, 708.8121647763347, 735.6983636708924, 754.728297812787, 788.4520258420342, 789.5539020453425, 5121.2574176744165, 5272.0, 5302.549283310962, 5316.893662126813, 5356.7321633918145, 130742.0, 136116.0, 136291.0, 139320.0, 141634.0, 117956.0, 119209.80790192161, 119633.2238404501, 121947.0, 122875.0, 103829.0, 106128.0, 106517.46741266399, 108693.45653271841, 109185.0, 9907.900920990985, 10144.0, 10181.847272290774, 10183.287169898036, 10253.179745620184, 48306.5169348302, 49634.0, 54019.189712153566, 59556.40443595508, 60552.39447605644, 50861.74569127131, 67428.6628566854, 78141.21858781339, 79182.60408697919, 81773.0, 13690.0, 13813.24027178515, 13833.861661383256, 13950.720985580434, 14335.139891606119, 15586.688266234838, 15706.842931570538, 15808.367665293259, 15844.920775396049, 15929.601759956095, 14162.575122746333, 14176.574702758933, 14316.212608306643, 14775.0, 15053.698926021638, 14116.364763585458, 14356.138631682132, 14383.208923509308, 14388.568342949728, 14774.63063423423, 10411.635592754217, 13197.142185758279, 13250.337483125611, 13557.932210338886, 14935.626609334853, 11801.0, 12142.39288035577, 12594.307313097868, 12647.683807904876, 12689.23864568129, 11357.318560886373, 11388.202825802115, 11533.308001519936, 11808.173427859967, 11829.76340473203], "value": 8036.236694506301, "unit": "score"}], "name": "dromaeo_css", "value": 7537.718182117351, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 120.0, 111.0, 109.0, 109.0, 108.0, 110.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 141.0, 164.0, 119.0, 146.0, 148.0, 117.0, 176.0, 122.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 158.0, 159.0, 159.0, 160.0, 159.0, 160.0, 160.0], "value": 159.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 179.0, 75.0, 76.0, 74.0, 74.0, 170.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 146.0, 87.0, 83.0, 83.0, 143.0, 83.0, 85.0, 141.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 53.0, 52.0, 53.0, 52.0, 56.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 73.0, 72.0, 77.0, 78.0, 73.0, 73.0, 72.0, 71.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 128.0, 116.0, 120.0, 127.0, 127.0, 120.0, 116.0, 151.0, 127.0], "value": 125.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 156.0, 150.0, 151.0, 158.0, 152.0, 151.0, 152.0, 154.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 60.0, 60.0, 64.0, 60.0, 59.0, 61.0], "value": 60.8, "unit": "score"}], "name": "kraken", "value": 1489.5, "alertThreshold": 2.0}]} 16:47:57 INFO - Return code: 0 16:47:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:57 INFO - # TBPL SUCCESS # 16:47:57 INFO - Running post-action listener: _resource_record_post_action 16:47:57 INFO - [mozharness: 2016-11-05 23:47:57.338834Z] Finished run-tests step (success) 16:47:57 INFO - Running post-run listener: _resource_record_post_run 16:47:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1867776; Write bytes: 291008512; Read time: 316; Write time: 155812 16:47:57 INFO - TinderboxPrint: CPU usage
12.9% 16:47:57 INFO - TinderboxPrint: I/O read bytes / time
1,867,776 / 316 16:47:57 INFO - TinderboxPrint: I/O write bytes / time
291,008,512 / 155,812 16:47:57 INFO - TinderboxPrint: CPU idle
8,250.0 (87.1%) 16:47:57 INFO - TinderboxPrint: CPU user
1,182.3 (12.5%) 16:47:57 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:57 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70111232; Read time: 0; Write time: 35744 16:47:57 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 220897280; Read time: 0; Write time: 120068 16:47:57 INFO - Running post-run listener: _upload_blobber_files 16:47:57 INFO - Blob upload gear active. 16:47:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:47:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:47:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:01 INFO - (blobuploader) - INFO - Done attempting. 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:01 INFO - (blobuploader) - INFO - Done attempting. 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:04 INFO - (blobuploader) - INFO - Done attempting. 16:48:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:04 INFO - Return code: 0 16:48:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04910376ff469bd8e2ae4017580b33631aba2370fdcd868189c172f4bad2704d9e2f6571cedf9d858c712af979e2f8e102fba58fadaf9b1be691f95f2ff5c957", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b939c76a0ef4487c3295600a39be9ac0eb998f78975f96327363df5937698471d1ce641765a2ef5f71d06b9d65c5d6381d77ac963ecdb82bb1d29e53987e3221", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a72f0481fae0918c7ed29765c5140899b5fdfc9c42a5c23b627bffcbdc570b5acc163edc85c43c9fe3d18eee01600356abf23533b1f9f2bee9c83a33461162"} 16:48:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:04 INFO - Contents: 16:48:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04910376ff469bd8e2ae4017580b33631aba2370fdcd868189c172f4bad2704d9e2f6571cedf9d858c712af979e2f8e102fba58fadaf9b1be691f95f2ff5c957", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b939c76a0ef4487c3295600a39be9ac0eb998f78975f96327363df5937698471d1ce641765a2ef5f71d06b9d65c5d6381d77ac963ecdb82bb1d29e53987e3221", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a72f0481fae0918c7ed29765c5140899b5fdfc9c42a5c23b627bffcbdc570b5acc163edc85c43c9fe3d18eee01600356abf23533b1f9f2bee9c83a33461162"} 16:48:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:04 INFO - Copying logs to upload dir... 16:48:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.963660 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:48:03.825698) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:03.831952) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04910376ff469bd8e2ae4017580b33631aba2370fdcd868189c172f4bad2704d9e2f6571cedf9d858c712af979e2f8e102fba58fadaf9b1be691f95f2ff5c957", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b939c76a0ef4487c3295600a39be9ac0eb998f78975f96327363df5937698471d1ce641765a2ef5f71d06b9d65c5d6381d77ac963ecdb82bb1d29e53987e3221", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a72f0481fae0918c7ed29765c5140899b5fdfc9c42a5c23b627bffcbdc570b5acc163edc85c43c9fe3d18eee01600356abf23533b1f9f2bee9c83a33461162"} build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04910376ff469bd8e2ae4017580b33631aba2370fdcd868189c172f4bad2704d9e2f6571cedf9d858c712af979e2f8e102fba58fadaf9b1be691f95f2ff5c957", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b939c76a0ef4487c3295600a39be9ac0eb998f78975f96327363df5937698471d1ce641765a2ef5f71d06b9d65c5d6381d77ac963ecdb82bb1d29e53987e3221", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a72f0481fae0918c7ed29765c5140899b5fdfc9c42a5c23b627bffcbdc570b5acc163edc85c43c9fe3d18eee01600356abf23533b1f9f2bee9c83a33461162"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:03.856999) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:03.857343) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1478388006.612468-808300895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:03.938850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:03.939172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:03.939470) ========= ========= Total master_lag: 0.17 =========