builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1478388009.49 results: success (0) buildid: 20161103181821 builduid: d984d1bb7c314f73889b19df79c575c9 revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.491571) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.492169) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.492546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.507431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.507741) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-05 16:20:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 56.4M=0s 2016-11-05 16:20:09 (56.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102940 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.621649) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.622071) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022177 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.671796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:09.672263) ========= 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=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-05 16:20:09,991 truncating revision to first 12 chars 2016-11-05 16:20:09,991 Setting DEBUG logging. 2016-11-05 16:20:09,991 attempt 1/10 2016-11-05 16:20:09,991 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:10,587 unpacking tar archive at: mozilla-beta-24b8f08f7756/testing/mozharness/ program finished with exit code 0 elapsedTime=0.948653 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.634903) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.635335) ========= script_repo_revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.635794) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.636096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:10.645492) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:20:10.645833) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:11 INFO - MultiFileLogger online at 20161105 16:20:11 in /builds/slave/test 16:20:11 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:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:11 INFO - {'append_to_log': False, 16:20:11 INFO - 'base_work_dir': '/builds/slave/test', 16:20:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:20:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:11 INFO - 'branch': 'Mozilla-Beta', 16:20:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:11 INFO - 'config_files': ('talos/linux_config.py',), 16:20:11 INFO - 'default_actions': ('clobber', 16:20:11 INFO - 'read-buildbot-config', 16:20:11 INFO - 'download-and-extract', 16:20:11 INFO - 'populate-webroot', 16:20:11 INFO - 'create-virtualenv', 16:20:11 INFO - 'install', 16:20:11 INFO - 'run-tests'), 16:20:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:11 INFO - 'download_minidump_stackwalk': True, 16:20:11 INFO - 'download_symbols': 'ondemand', 16:20:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:11 INFO - 'installer_path': 'installer.exe', 16:20:11 INFO - 'log_level': 'info', 16:20:11 INFO - 'log_name': 'talos', 16:20:11 INFO - 'log_to_console': True, 16:20:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:11 INFO - 'opt_config_files': (), 16:20:11 INFO - 'pip_index': False, 16:20:11 INFO - 'sps_profile': False, 16:20:11 INFO - 'sps_profile_interval': 0, 16:20:11 INFO - 'suite': 'dromaeojs', 16:20:11 INFO - 'system_bits': '32', 16:20:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:11 INFO - 'title': 'talos-linux64-ix-031', 16:20:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:11 INFO - 'use_talos_json': True, 16:20:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:11 INFO - 'work_dir': 'build'} 16:20:11 INFO - [mozharness: 2016-11-05 23:20:11.053913Z] Running clobber step. 16:20:11 INFO - Running pre-action listener: _resource_record_pre_action 16:20:11 INFO - Running main action method: clobber 16:20:11 INFO - rmtree: /builds/slave/test/build 16:20:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:11 INFO - Running post-action listener: _resource_record_post_action 16:20:11 INFO - [mozharness: 2016-11-05 23:20:11.515520Z] Finished clobber step (success) 16:20:11 INFO - [mozharness: 2016-11-05 23:20:11.515620Z] Running read-buildbot-config step. 16:20:11 INFO - Running pre-action listener: _resource_record_pre_action 16:20:11 INFO - Running main action method: read_buildbot_config 16:20:11 INFO - Using buildbot properties: 16:20:11 INFO - { 16:20:11 INFO - "project": "", 16:20:11 INFO - "product": "firefox", 16:20:11 INFO - "script_repo_revision": "production", 16:20:11 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:20:11 INFO - "repository": "", 16:20:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:20:11 INFO - "buildid": "20161103181821", 16:20:11 INFO - "pgo_build": "True", 16:20:11 INFO - "basedir": "/builds/slave/test", 16:20:11 INFO - "buildnumber": 1, 16:20:11 INFO - "slavename": "talos-linux64-ix-031", 16:20:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:20:11 INFO - "platform": "ubuntu64_hw", 16:20:11 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:20:11 INFO - "branch": "mozilla-beta", 16:20:11 INFO - "repo_path": "releases/mozilla-beta", 16:20:11 INFO - "revision": "24b8f08f77565f859898b45f62d2132ccc64c6d8", 16:20:11 INFO - "stage_platform": "linux64-pgo", 16:20:11 INFO - "builduid": "d984d1bb7c314f73889b19df79c575c9", 16:20:11 INFO - "slavebuilddir": "test" 16:20:11 INFO - } 16:20:11 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:11 INFO - Running post-action listener: _resource_record_post_action 16:20:11 INFO - [mozharness: 2016-11-05 23:20:11.518066Z] Finished read-buildbot-config step (success) 16:20:11 INFO - [mozharness: 2016-11-05 23:20:11.518155Z] Running download-and-extract step. 16:20:11 INFO - Running pre-action listener: _resource_record_pre_action 16:20:11 INFO - Running main action method: download_and_extract 16:20:11 INFO - mkdir: /builds/slave/test/build/tests 16:20:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:20:11 INFO - retry: Failed, sleeping 30 seconds before retrying 16:20:41 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:41 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:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:20:41 INFO - retry: Failed, sleeping 60 seconds before retrying 16:21:41 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:41 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:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:21:41 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:41 INFO - Caught exception: HTTP Error 404: Not Found 16:21:41 INFO - Caught exception: HTTP Error 404: Not Found 16:21:41 INFO - Caught exception: HTTP Error 404: Not Found 16:21:41 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:41 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:41 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:42 INFO - Downloaded 1327 bytes. 16:21:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:42 INFO - Using the following test package requirements: 16:21:42 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:42 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:21:42 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:21:42 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'jsshell-linux-x86_64.zip'], 16:21:42 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:21:42 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:42 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:21:42 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:21:42 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:21:42 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:42 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:21:42 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:42 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:42 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:42 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:42 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: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 #1 16:21: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:21:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:21:42 INFO - retry: Failed, sleeping 30 seconds before retrying 16:22: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 #2 16:22: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:22:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:22:12 INFO - retry: Failed, sleeping 60 seconds before retrying 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 #3 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 #3 caught exception: HTTP Error 404: Not Found 16:23:12 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:12 INFO - Caught exception: HTTP Error 404: Not Found 16:23:12 INFO - Caught exception: HTTP Error 404: Not Found 16:23:12 INFO - Caught exception: HTTP Error 404: Not Found 16:23:12 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:12 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:12 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:13 INFO - Downloaded 21787606 bytes. 16:23:13 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:13 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:14 INFO - Return code: 0 16:23:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:23:14 INFO - retry: Failed, sleeping 30 seconds before retrying 16:23:44 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:44 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:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:23:44 INFO - retry: Failed, sleeping 60 seconds before retrying 16:24:44 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:44 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:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:24:44 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:44 INFO - Caught exception: HTTP Error 404: Not Found 16:24:44 INFO - Caught exception: HTTP Error 404: Not Found 16:24:44 INFO - Caught exception: HTTP Error 404: Not Found 16:24:44 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:44 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:44 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:45 INFO - Downloaded 21787606 bytes. 16:24:45 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:45 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:46 INFO - Return code: 0 16:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:46 INFO - 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:46 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:46 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:46 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:46 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: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.talos.tests.zip 16:24:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:24:46 INFO - retry: Failed, sleeping 30 seconds before retrying 16:25:16 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: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.talos.tests.zip 16:25:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:25:16 INFO - retry: Failed, sleeping 60 seconds before retrying 16:26:16 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: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.talos.tests.zip 16:26:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:26:16 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:16 INFO - Caught exception: HTTP Error 404: Not Found 16:26:16 INFO - Caught exception: HTTP Error 404: Not Found 16:26:16 INFO - Caught exception: HTTP Error 404: Not Found 16:26:16 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:16 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:16 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:17 INFO - Downloaded 14413770 bytes. 16:26:17 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:17 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:17 INFO - Return code: 0 16:26:17 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:17 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:17 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:17 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:17 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:17 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:17 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:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:26:17 INFO - retry: Failed, sleeping 30 seconds before retrying 16:26:47 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:47 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:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:26:47 INFO - retry: Failed, sleeping 60 seconds before retrying 16:27:47 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:47 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:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:27:47 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:47 INFO - Caught exception: HTTP Error 404: Not Found 16:27:47 INFO - Caught exception: HTTP Error 404: Not Found 16:27:47 INFO - Caught exception: HTTP Error 404: Not Found 16:27:47 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:47 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:47 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:49 INFO - Downloaded 56799929 bytes. 16:27:49 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:49 INFO - mkdir: /builds/slave/test/properties 16:27:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:49 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:49 INFO - Contents: 16:27:49 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:49 INFO - Running post-action listener: _resource_record_post_action 16:27:49 INFO - Running post-action listener: set_extra_try_arguments 16:27:49 INFO - [mozharness: 2016-11-05 23:27:49.762418Z] Finished download-and-extract step (success) 16:27:49 INFO - [mozharness: 2016-11-05 23:27:49.762650Z] Running populate-webroot step. 16:27:49 INFO - Running pre-action listener: _resource_record_pre_action 16:27:49 INFO - Running main action method: populate_webroot 16:27:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:49 INFO - '--tppagecycles', 16:27:49 INFO - '7'], 16:27:49 INFO - 'tests': ['tsvgm']}}, 16:27:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['damp', 'tps']}, 16:27:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'tests': ['damp', 'tps']}, 16:27:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['dromaeo_dom']}, 16:27:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['basic_compositor_video']}, 16:27:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['tsvgx', 16:27:49 INFO - 'tsvgr_opacity', 16:27:49 INFO - 'tart', 16:27:49 INFO - 'tscrollx', 16:27:49 INFO - 'cart']}, 16:27:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:49 INFO - 'tsvgr_opacity', 16:27:49 INFO - 'tart', 16:27:49 INFO - 'tscrollx', 16:27:49 INFO - 'cart']}, 16:27:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-e10s'], 16:27:49 INFO - 'tests': ['tp5o']}, 16:27:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'tests': ['tp5o']}, 16:27:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-e10s', 16:27:49 INFO - '--xperf_path', 16:27:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:49 INFO - 'tests': ['tp5n']}, 16:27:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:49 INFO - 'talos_options': ['--xperf_path', 16:27:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:49 INFO - 'tests': ['tp5n']}}, 16:27:49 INFO - 'talos.zip': {'path': '', 16:27:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:49 INFO - Running post-action listener: _resource_record_post_action 16:27:49 INFO - [mozharness: 2016-11-05 23:27:49.782753Z] Finished populate-webroot step (success) 16:27:49 INFO - [mozharness: 2016-11-05 23:27:49.782842Z] Running create-virtualenv step. 16:27:49 INFO - Running pre-action listener: _resource_record_pre_action 16:27:49 INFO - Running main action method: create_virtualenv 16:27:49 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:49 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:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:49 INFO - Using real prefix '/usr' 16:27:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:50 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:52 INFO - Installing pip.................done. 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:27:52 INFO - Using env: {'DISPLAY': ':0', 16:27:52 INFO - 'HOME': '/home/cltbld', 16:27:52 INFO - 'LANG': 'en_US.UTF-8', 16:27:52 INFO - 'LANGUAGE': 'en_US:en', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MAIL': '/var/mail/cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SHLVL': '1', 16:27:52 INFO - 'TERM': 'linux', 16:27:52 INFO - 'TMOUT': '86400', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 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:52 INFO - Cleaning up... 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:27:52 INFO - Using env: {'DISPLAY': ':0', 16:27:52 INFO - 'HOME': '/home/cltbld', 16:27:52 INFO - 'LANG': 'en_US.UTF-8', 16:27:52 INFO - 'LANGUAGE': 'en_US:en', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MAIL': '/var/mail/cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SHLVL': '1', 16:27:52 INFO - 'TERM': 'linux', 16:27:52 INFO - 'TMOUT': '86400', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 INFO - Downloading/unpacking psutil>=3.1.1 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:54 INFO - Installing collected packages: psutil 16:27:54 INFO - Running setup.py install for psutil 16:27:54 INFO - building 'psutil._psutil_linux' extension 16:27:54 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:55 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:55 INFO - building 'psutil._psutil_posix' extension 16:27:55 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:55 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:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:55 INFO - Successfully installed psutil 16:27:55 INFO - Cleaning up... 16:27:55 INFO - Return code: 0 16:27:55 INFO - Installing mozsystemmonitor==0.3 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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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', 'mozsystemmonitor==0.3'] 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 mozsystemmonitor==0.3 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': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:55 INFO - '_': '/tools/buildbot/bin/python'} 16:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:55 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:55 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 - 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:55 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:55 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:55 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:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:57 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:57 INFO - Installing collected packages: mozsystemmonitor 16:27:57 INFO - Running setup.py install for mozsystemmonitor 16:27:57 INFO - Successfully installed mozsystemmonitor 16:27:57 INFO - Cleaning up... 16:27:57 INFO - Return code: 0 16:27:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:27:57 INFO - Using env: {'DISPLAY': ':0', 16:27:57 INFO - 'HOME': '/home/cltbld', 16:27:57 INFO - 'LANG': 'en_US.UTF-8', 16:27:57 INFO - 'LANGUAGE': 'en_US:en', 16:27:57 INFO - 'LOGNAME': 'cltbld', 16:27:57 INFO - 'MAIL': '/var/mail/cltbld', 16:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:57 INFO - 'MOZ_NO_REMOTE': '1', 16:27:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:57 INFO - 'NO_EM_RESTART': '1', 16:27:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:57 INFO - 'PWD': '/builds/slave/test', 16:27:57 INFO - 'SHELL': '/bin/bash', 16:27:57 INFO - 'SHLVL': '1', 16:27:57 INFO - 'TERM': 'linux', 16:27:57 INFO - 'TMOUT': '86400', 16:27:57 INFO - 'USER': 'cltbld', 16:27:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:57 INFO - '_': '/tools/buildbot/bin/python'} 16:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:57 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:59 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:59 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:59 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:59 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:59 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:59 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 functools32-3.2.3-2.tar.gz 16:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:00 INFO - warning: no files found matching '*.txt' 16:28:00 INFO - no previously-included directories found matching 'build' 16:28:00 INFO - no previously-included directories found matching 'dist' 16:28:00 INFO - no previously-included directories found matching '.git*' 16:28:00 INFO - Installing collected packages: jsonschema, functools32 16:28:00 INFO - Running setup.py install for functools32 16:28:00 INFO - warning: no files found matching '*.txt' 16:28:00 INFO - no previously-included directories found matching 'build' 16:28:00 INFO - no previously-included directories found matching 'dist' 16:28:00 INFO - no previously-included directories found matching '.git*' 16:28:00 INFO - Successfully installed jsonschema functools32 16:28:00 INFO - Cleaning up... 16:28:00 INFO - Return code: 0 16:28:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:28:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:00 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:00 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:28:00 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:28:00 INFO - Using env: {'DISPLAY': ':0', 16:28:00 INFO - 'HOME': '/home/cltbld', 16:28:00 INFO - 'LANG': 'en_US.UTF-8', 16:28:00 INFO - 'LANGUAGE': 'en_US:en', 16:28:00 INFO - 'LOGNAME': 'cltbld', 16:28:00 INFO - 'MAIL': '/var/mail/cltbld', 16:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:00 INFO - 'MOZ_NO_REMOTE': '1', 16:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:00 INFO - 'NO_EM_RESTART': '1', 16:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:00 INFO - 'PWD': '/builds/slave/test', 16:28:00 INFO - 'SHELL': '/bin/bash', 16:28:00 INFO - 'SHLVL': '1', 16:28:00 INFO - 'TERM': 'linux', 16:28:00 INFO - 'TMOUT': '86400', 16:28:00 INFO - 'USER': 'cltbld', 16:28:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:00 INFO - '_': '/tools/buildbot/bin/python'} 16:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:28:00 INFO - Cleaning up... 16:28:00 INFO - Return code: 0 16:28:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:28:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:00 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:00 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:28:00 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:28:00 INFO - Using env: {'DISPLAY': ':0', 16:28:00 INFO - 'HOME': '/home/cltbld', 16:28:00 INFO - 'LANG': 'en_US.UTF-8', 16:28:00 INFO - 'LANGUAGE': 'en_US:en', 16:28:00 INFO - 'LOGNAME': 'cltbld', 16:28:00 INFO - 'MAIL': '/var/mail/cltbld', 16:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:00 INFO - 'MOZ_NO_REMOTE': '1', 16:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:00 INFO - 'NO_EM_RESTART': '1', 16:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:00 INFO - 'PWD': '/builds/slave/test', 16:28:00 INFO - 'SHELL': '/bin/bash', 16:28:00 INFO - 'SHLVL': '1', 16:28:00 INFO - 'TERM': 'linux', 16:28:00 INFO - 'TMOUT': '86400', 16:28:00 INFO - 'USER': 'cltbld', 16:28:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:00 INFO - '_': '/tools/buildbot/bin/python'} 16:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:00 INFO - Downloading/unpacking 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:02 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:02 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:02 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:02 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:02 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:02 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:02 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:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:03 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:03 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:03 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:03 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:03 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:03 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:03 INFO - Downloading docopt-0.6.1.tar.gz 16:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:03 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:03 INFO - Running setup.py install for blobuploader 16:28:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:03 INFO - Running setup.py install for requests 16:28:03 INFO - Running setup.py install for docopt 16:28:04 INFO - Successfully installed blobuploader requests docopt 16:28:04 INFO - Cleaning up... 16:28:04 INFO - Return code: 0 16:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:04 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:04 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:04 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:04 INFO - Using env: {'DISPLAY': ':0', 16:28:04 INFO - 'HOME': '/home/cltbld', 16:28:04 INFO - 'LANG': 'en_US.UTF-8', 16:28:04 INFO - 'LANGUAGE': 'en_US:en', 16:28:04 INFO - 'LOGNAME': 'cltbld', 16:28:04 INFO - 'MAIL': '/var/mail/cltbld', 16:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:04 INFO - 'MOZ_NO_REMOTE': '1', 16:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:04 INFO - 'NO_EM_RESTART': '1', 16:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:04 INFO - 'PWD': '/builds/slave/test', 16:28:04 INFO - 'SHELL': '/bin/bash', 16:28:04 INFO - 'SHLVL': '1', 16:28:04 INFO - 'TERM': 'linux', 16:28:04 INFO - 'TMOUT': '86400', 16:28:04 INFO - 'USER': 'cltbld', 16:28:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:04 INFO - '_': '/tools/buildbot/bin/python'} 16:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:04 INFO - Running setup.py (path:/tmp/pip-c2q5D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:04 INFO - Running setup.py (path:/tmp/pip-e22PBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:04 INFO - Running setup.py (path:/tmp/pip-Krl4pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:04 INFO - Running setup.py (path:/tmp/pip-3miMRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:04 INFO - Running setup.py (path:/tmp/pip-nU_bML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:04 INFO - Running setup.py (path:/tmp/pip-BrEYmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:04 INFO - Running setup.py (path:/tmp/pip-pks9EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:04 INFO - Running setup.py (path:/tmp/pip-MfS6_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:04 INFO - Running setup.py (path:/tmp/pip-2F5t_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:05 INFO - Running setup.py (path:/tmp/pip-_NLion-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:05 INFO - Running setup.py (path:/tmp/pip-3M4Gu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:05 INFO - Running setup.py (path:/tmp/pip-pkEjY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:05 INFO - Running setup.py (path:/tmp/pip-0r1Ewh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:05 INFO - Running setup.py (path:/tmp/pip-kgZ3_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:05 INFO - Running setup.py (path:/tmp/pip-9ZRUBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:05 INFO - Running setup.py (path:/tmp/pip-FgYokp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:05 INFO - Running setup.py (path:/tmp/pip-n_y1sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:05 INFO - Running setup.py install for manifestparser 16:28:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Running setup.py install for mozcrash 16:28:05 INFO - Running setup.py install for mozdebug 16:28:05 INFO - Running setup.py install for mozdevice 16:28:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozfile 16:28:06 INFO - Running setup.py install for mozhttpd 16:28:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozinfo 16:28:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozInstall 16:28:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozleak 16:28:06 INFO - Running setup.py install for mozlog 16:28:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for moznetwork 16:28:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozprocess 16:28:06 INFO - Running setup.py install for mozprofile 16:28:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:06 INFO - Running setup.py install for mozrunner 16:28:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:07 INFO - Running setup.py install for mozscreenshot 16:28:07 INFO - Running setup.py install for moztest 16:28:07 INFO - Running setup.py install for mozversion 16:28:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:07 INFO - Cleaning up... 16:28:07 INFO - Return code: 0 16:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:07 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:07 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:07 INFO - Using env: {'DISPLAY': ':0', 16:28:07 INFO - 'HOME': '/home/cltbld', 16:28:07 INFO - 'LANG': 'en_US.UTF-8', 16:28:07 INFO - 'LANGUAGE': 'en_US:en', 16:28:07 INFO - 'LOGNAME': 'cltbld', 16:28:07 INFO - 'MAIL': '/var/mail/cltbld', 16:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:07 INFO - 'MOZ_NO_REMOTE': '1', 16:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:07 INFO - 'NO_EM_RESTART': '1', 16:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:07 INFO - 'PWD': '/builds/slave/test', 16:28:07 INFO - 'SHELL': '/bin/bash', 16:28:07 INFO - 'SHLVL': '1', 16:28:07 INFO - 'TERM': 'linux', 16:28:07 INFO - 'TMOUT': '86400', 16:28:07 INFO - 'USER': 'cltbld', 16:28:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:07 INFO - '_': '/tools/buildbot/bin/python'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:07 INFO - Running setup.py (path:/tmp/pip-Ha3waM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:07 INFO - Running setup.py (path:/tmp/pip-ygOzXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:07 INFO - Running setup.py (path:/tmp/pip-sI5b54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:07 INFO - Running setup.py (path:/tmp/pip-bstWNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:07 INFO - Running setup.py (path:/tmp/pip-JynGlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:07 INFO - Running setup.py (path:/tmp/pip-upltAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:08 INFO - Running setup.py (path:/tmp/pip-K4y0mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:08 INFO - Running setup.py (path:/tmp/pip-tEoBjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:08 INFO - Running setup.py (path:/tmp/pip-TGlvk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:08 INFO - Running setup.py (path:/tmp/pip-P0PTub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:08 INFO - Running setup.py (path:/tmp/pip-VzU8y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:08 INFO - Running setup.py (path:/tmp/pip-KOS3GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:08 INFO - Running setup.py (path:/tmp/pip-Ai5WVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:08 INFO - Running setup.py (path:/tmp/pip-ovmSBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:08 INFO - Running setup.py (path:/tmp/pip-p78AlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:08 INFO - Running setup.py (path:/tmp/pip-Pb4Esb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:08 INFO - Running setup.py (path:/tmp/pip-MOB9X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:08 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:08 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:08 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:08 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:08 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:08 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:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:08 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:08 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 - 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:08 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:08 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:08 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:10 INFO - Downloading blessings-1.6.tar.gz 16:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:10 INFO - Installing collected packages: blessings 16:28:10 INFO - Running setup.py install for blessings 16:28:10 INFO - Successfully installed blessings 16:28:10 INFO - Cleaning up... 16:28:11 INFO - Return code: 0 16:28:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:11 INFO - Reading from file tmpfile_stdout 16:28:11 INFO - Current package versions: 16:28:11 INFO - argparse == 1.2.1 16:28:11 INFO - blessings == 1.6 16:28:11 INFO - blobuploader == 1.2.4 16:28:11 INFO - docopt == 0.6.1 16:28:11 INFO - functools32 == 3.2.3-2 16:28:11 INFO - jsonschema == 2.5.1 16:28:11 INFO - manifestparser == 1.1 16:28:11 INFO - mozInstall == 1.12 16:28:11 INFO - mozcrash == 0.17 16:28:11 INFO - mozdebug == 0.1 16:28:11 INFO - mozdevice == 0.48 16:28:11 INFO - mozfile == 1.2 16:28:11 INFO - mozhttpd == 0.7 16:28:11 INFO - mozinfo == 0.9 16:28:11 INFO - mozleak == 0.1 16:28:11 INFO - mozlog == 3.3 16:28:11 INFO - moznetwork == 0.27 16:28:11 INFO - mozprocess == 0.23 16:28:11 INFO - mozprofile == 0.28 16:28:11 INFO - mozrunner == 6.12 16:28:11 INFO - mozscreenshot == 0.1 16:28:11 INFO - mozsystemmonitor == 0.3 16:28:11 INFO - moztest == 0.7 16:28:11 INFO - mozversion == 1.4 16:28:11 INFO - psutil == 3.1.1 16:28:11 INFO - requests == 1.2.3 16:28:11 INFO - wsgiref == 0.1.2 16:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:11 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:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:11 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 16:28:11 INFO - 'HOME': '/home/cltbld', 16:28:11 INFO - 'LANG': 'en_US.UTF-8', 16:28:11 INFO - 'LANGUAGE': 'en_US:en', 16:28:11 INFO - 'LOGNAME': 'cltbld', 16:28:11 INFO - 'MAIL': '/var/mail/cltbld', 16:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:11 INFO - 'MOZ_NO_REMOTE': '1', 16:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:11 INFO - 'NO_EM_RESTART': '1', 16:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:11 INFO - 'PWD': '/builds/slave/test', 16:28:11 INFO - 'SHELL': '/bin/bash', 16:28:11 INFO - 'SHLVL': '1', 16:28:11 INFO - 'TERM': 'linux', 16:28:11 INFO - 'TMOUT': '86400', 16:28:11 INFO - 'USER': 'cltbld', 16:28:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:11 INFO - '_': '/tools/buildbot/bin/python'} 16:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 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:11 INFO - Cleaning up... 16:28:11 INFO - Return code: 0 16:28:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:11 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:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:11 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 0x1c77030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dda8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f38180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 16:28:11 INFO - 'HOME': '/home/cltbld', 16:28:11 INFO - 'LANG': 'en_US.UTF-8', 16:28:11 INFO - 'LANGUAGE': 'en_US:en', 16:28:11 INFO - 'LOGNAME': 'cltbld', 16:28:11 INFO - 'MAIL': '/var/mail/cltbld', 16:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:11 INFO - 'MOZ_NO_REMOTE': '1', 16:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:11 INFO - 'NO_EM_RESTART': '1', 16:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:11 INFO - 'PWD': '/builds/slave/test', 16:28:11 INFO - 'SHELL': '/bin/bash', 16:28:11 INFO - 'SHLVL': '1', 16:28:11 INFO - 'TERM': 'linux', 16:28:11 INFO - 'TMOUT': '86400', 16:28:11 INFO - 'USER': 'cltbld', 16:28:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:11 INFO - '_': '/tools/buildbot/bin/python'} 16:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:11 INFO - Cleaning up... 16:28:11 INFO - Return code: 0 16:28:11 INFO - Running post-action listener: _resource_record_post_action 16:28:11 INFO - Running post-action listener: _start_resource_monitoring 16:28:11 INFO - Starting resource monitoring. 16:28:11 INFO - [mozharness: 2016-11-05 23:28:11.692414Z] Finished create-virtualenv step (success) 16:28:11 INFO - [mozharness: 2016-11-05 23:28:11.692850Z] Running install step. 16:28:11 INFO - Running pre-action listener: _resource_record_pre_action 16:28:11 INFO - Running main action method: install 16:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:11 INFO - Reading from file tmpfile_stdout 16:28:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:11 INFO - Reading from file tmpfile_stdout 16:28:11 INFO - Output received: 16:28:11 INFO - Usage: mozinstall [options] installer 16:28:11 INFO - Options: 16:28:11 INFO - -h, --help show this help message and exit 16:28:11 INFO - -d DEST, --destination=DEST 16:28:11 INFO - Directory to install application into. [default: 16:28:11 INFO - "/builds/slave/test"] 16:28:11 INFO - --app=APP Application being installed. [default: firefox] 16:28:11 INFO - mkdir: /builds/slave/test/build/application 16:28:11 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:11 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:25 INFO - Reading from file tmpfile_stdout 16:28:25 INFO - Output received: 16:28:25 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:25 INFO - Running post-action listener: _resource_record_post_action 16:28:25 INFO - [mozharness: 2016-11-05 23:28:25.465491Z] Finished install step (success) 16:28:25 INFO - [mozharness: 2016-11-05 23:28:25.465769Z] Running run-tests step. 16:28:25 INFO - Running pre-action listener: _resource_record_pre_action 16:28:25 INFO - Running main action method: run_tests 16:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:25 INFO - Python 2.7.3 16:28:25 INFO - Return code: 0 16:28:25 INFO - grabbing minidump binary from tooltool 16:28:25 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:25 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 0x1f363b0>, '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 0x1f372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f37740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:25 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:25 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:25 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:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:25 INFO - Return code: 0 16:28:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:25 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-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:28:25 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-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:28:25 INFO - Using env: {'DISPLAY': ':0', 16:28:25 INFO - 'HOME': '/home/cltbld', 16:28:25 INFO - 'LANG': 'en_US.UTF-8', 16:28:25 INFO - 'LANGUAGE': 'en_US:en', 16:28:25 INFO - 'LOGNAME': 'cltbld', 16:28:25 INFO - 'MAIL': '/var/mail/cltbld', 16:28:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:25 INFO - 'MOZ_NO_REMOTE': '1', 16:28:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:25 INFO - 'NO_EM_RESTART': '1', 16:28:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:25 INFO - 'PWD': '/builds/slave/test', 16:28:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:25 INFO - 'SHELL': '/bin/bash', 16:28:25 INFO - 'SHLVL': '1', 16:28:25 INFO - 'TERM': 'linux', 16:28:25 INFO - 'TMOUT': '86400', 16:28:25 INFO - 'USER': 'cltbld', 16:28:25 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874', 16:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:25 INFO - '_': '/tools/buildbot/bin/python'} 16:28:25 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-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:28:25 INFO - mozversion application_buildid: 20161103181821 16:28:25 INFO - mozversion application_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:25 INFO - mozversion application_display_name: Firefox 16:28:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:25 INFO - mozversion application_name: Firefox 16:28:25 INFO - mozversion application_remotingname: firefox 16:28:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:25 INFO - mozversion application_vendor: Mozilla 16:28:25 INFO - mozversion application_version: 50.0 16:28:25 INFO - mozversion platform_buildid: 20161103181821 16:28:25 INFO - mozversion platform_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:25 INFO - mozversion platform_version: 50.0 16:28:25 INFO - using testdate: 1478388505 16:28:25 INFO - actual date: 1478388505 16:28:25 INFO - starting webserver on 'localhost:55623' 16:28:25 INFO - SUITE-START | Running 2 tests 16:28:25 INFO - TEST-START | dromaeo_css 16:28:25 INFO - operating with platform_type : linux_ 16:28:25 INFO - Initialising browser for dromaeo_css test... 16:28:25 INFO - TEST-INFO | started process 18375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgJNze/profile http://localhost:55623/getInfo.html) 16:28:29 INFO - PROCESS | 18375 | __metrics Screen width/height:1600/1200 16:28:29 INFO - PROCESS | 18375 | colorDepth:24 16:28:29 INFO - PROCESS | 18375 | Browser inner width/height: 1024/697 16:28:29 INFO - PROCESS | 18375 | __metrics 16:28:30 INFO - PROCESS | 18375 | 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:30 INFO - PROCESS | 18375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:31 INFO - TEST-INFO | 18375: exit 0 16:28:31 INFO - Browser initialized. 16:28:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:31 INFO - TEST-INFO | started process 18463 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgJNze/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:43 INFO - PROCESS | 18463 | RSS: Main: 144248832 16:28:43 INFO - PROCESS | 18463 | 16:29:34 INFO - PROCESS | 18463 | 1478388574070 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:43 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55623/tests/dromaeo/cssquery-ext.html) 16:31:44 INFO - PROCESS | 18463 | RSS: Main: 255807488 16:31:44 INFO - PROCESS | 18463 | 16:34:46 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-ext.html (next: http://localhost:55623/tests/dromaeo/cssquery-jquery.html) 16:34:47 INFO - PROCESS | 18463 | RSS: Main: 235143168 16:34:47 INFO - PROCESS | 18463 | 16:37:48 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55623/tests/dromaeo/cssquery-mootools.html) 16:37:49 INFO - PROCESS | 18463 | RSS: Main: 214933504 16:37:49 INFO - PROCESS | 18463 | 16:40:51 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55623/tests/dromaeo/cssquery-prototype.html) 16:40:52 INFO - PROCESS | 18463 | RSS: Main: 218099712 16:40:52 INFO - PROCESS | 18463 | 16:43:54 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55623/tests/dromaeo/cssquery-yui.html) 16:43:55 INFO - PROCESS | 18463 | RSS: Main: 179982336 16:43:55 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | Cycle 1(1): loaded http://localhost:55623/tests/dromaeo/cssquery-yui.html (next: http://localhost:55623/tests/dromaeo/cssquery-dojo.html) 16:46:57 INFO - PROCESS | 18463 | RSS: Main: 258514944 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | __start_tp_report 16:46:57 INFO - PROCESS | 18463 | _x_x_mozilla_page_load 16:46:57 INFO - PROCESS | 18463 | _x_x_mozilla_page_load_details 16:46:57 INFO - PROCESS | 18463 | |i|pagename|runs| 16:46:57 INFO - PROCESS | 18463 | |0;dojo.html;3217.195701074731;3221.3396253767974;3225.629052658945;3259.462188738858;3285.2279714267142;5594.132909399044;5698.173764804106;5698.971505142468;5709.086546152616;5731.598788084836;5656.179853921179;5708.257926469553;5712.828615141542;5716.8284951451415;5733.426657334265;5149;5159.174532074869;5164.354455693038;5222.216667499886;5224.92162617561;5070.974645126769;5228.660137091077;5244.842654720345;5251.842444726664;5277.604179686519;5039.21892106724;5072.26452164436;5093.134167191586;5116.1302578561545;5151.845444636667;5069.543741063304;5104.285400043997;5205.349331333583;5207.969241523951;5245.31810864589;4958.677685950402;5013.749312534359;5068.493150684902;5144.768485418128;5167.250748641463;5517.034518959206;5523.364813046511;5523.889522209533;5545.556355491551;5598.608097433182;5936.97031514844;5977.521798256129;6035.486983606399;6038.064100064497;6042.244719410074;5886.70566471679;6007.669578173199;6066.211392518944;6076.574639775174;6080.179175811253;5560.471755183251;5624.35860392361;5640.351359593617;5647.943520564824;5730.942690573041;6251.874962500725;6260.186175797117;6260.937390626035;6265.373462653789;6268.686565671698;5730.169125476783;5761.3950535194035;5980.162777211194;5987.820365389045;5988.490978266809;5954.612950158225;5955.0769630706445;5998.460138587461;6046.062860256668;6047.848803779941;6065.636061836304;6074.75700971965;6084.330723620398;6093.969530152321;6113;5976.880462390728;6021.849453763691;6087;6098.5426093042315;6099.2680878294;4472.105578884236;4552.704074235164;4556.453225612948;4672.415948006499;4682.1103990241745;4110.547839737626;4116.897077573085;4128.545859955462;4128.979355103205;4159.8960025999595;5721.914171287434;5724.398938111438;5726.341470730836;5730.627509211888;5733.569982251348;5892.6759028253855;5998.130271110752;6051.213342265565;6055.909161362583;6059.666718330446;5739.311282646109;5798.246227990334;5802.593818432753;5808.477237048686;5809.186713860063;5292.047431462297;5297.788088476418;5306.363236411656;5308;5309.2301616266195;6040.939590604038;6117.096718007469;6172.2901866284155;6313.684315784192;6323.936760632427;6067.484263837623;6083.513318934563;6089.391060893875;6111.755529778759;6127.417895299939;6192.659403732838;6231.554598388639;6255.749770009149;6269.185005949197;6271.592346497463;5771.624844385102;5894.410558944071;5910.202122713378;5944.256967879015;5973.761049557969;6110.6028108174605;6124.838682352551;6125.632272693224;6127.617148554337;6127.769884593299;5989.071693887455;5991.101334799815;5992.490638295793;5993.760249589967;6020.458158765732;5803.158542011469;5841.883162336814;5883.294004719461;5912.46787789101;5981.491573216325;5889.676067816311;5897.056470964625;5901.350851406428;5912.67480288571;5933.228680271537;5935.614185077956;5968.104784282219;5975.611585246945;5978.641281523122;6016.969915150396;5661.264035675335;5687.60186786921;5773.1051686988585;5836.328822185334;5840.036393994943 16:46:57 INFO - PROCESS | 18463 | |1;ext.html;593.851537115721;641.2753588445057;666.5434177588351;668.8060462465885;670.3564578005112;33860.32279354412;44831.77584112079;45710;45711.54288457114;45744.771276143656;48481;48980.75509622447;48987.265191022074;48988.26517602239;49017.50982490174;8436.915630843705;9220.216281616054;9338.673146439876;9416.199623032051;9416.717498475056;10733.731656708567;11009.724756881062;11016.339019658846;11411.31532108072;11417.086633069375;11172.608958686447;11272.323660580392;11275.66173014811;11288;11288.0969522438;11098.500567474443;11116.610918617891;11135.220534562543;11148.554057837677;11190.944045279803;10936.507857146447;11089.168312376523;11102.278351907182;11105.83341249882;11108.222424430292;45400;45614.31578526325;45744.54255457469;45761;45770.08459830818;3730.496382988289;4012.277789997799;4158.6465150462245;4208.684348673831;4647.279671650899;34999.825000875084;35045.82477087598;35050.824745876365;35216.29567408663;35248;4544.500104988449;4557.56703113207;4604.516525764813;4697.976510117462;4706.552877476634;242774;242897.78551107307;243768;244711;246858;63871;64180;64208.67895660492;64267.67866160639;64340.67829660822;39625.40561891574;40260.59739402569;40323.19353612911;42288.78855605702;67363;19324.420267392;19479.220831166876;19534.218631254877;19537.12082956242;19543;13179.604611861469;14739.189344585937;14965.40138394452;15025.699486010219;15105.169215693022;21032;21227.46931326698;21233.36299911005;21318.253861115096;21327.573448530944;7456.32893039629;7533.924660753432;7602.163761986177;7615.390768738488;7702.152763196044;1053.8155822730992;1062.845887346346;1120.7198200449889;1121.484117306032;1198.7602479504135;34570;34694.82652586721;34695.65304346975;35234.8238258807;35280.82359588237;1166.5217260922982;1192.6839387562302;1194.5281613762515;1273.3569547378515;1329.7274058817918;18538;18597.81402185988;18654.36744744863;18737.625247494972;18788.154533046047;695866;702589;706389;706492;707878;63972;64392.67803660952;64769;64807;64828.35171648223;1369.7260547890262;1468.9265536723335;1476.0774515927546;1505.954821355361;1508.057464084947;33823;35028.29943401067;35137.29725405426;35349.29301414009;35375;54612.453875460735;54808.17787733107;55095.72452137714;55297.44702552923;55450;469142;470551;471025;471320;474926;27523.174304770888;29978.400431991675;30612.693873060984;30746.385072298875;31061;51769.741151294;51864;52714.837109773536;52799;57354.42645573491;45287;45736.771316143204;46211.537884620724;46475.535244648476;46970.76514617405;43741.856859063446;45823.541764581925;45855.77072114618;46411.53588464072;47400.28899566578;45165.54834451614;45463.545364545935;45517.77241113773;45571;45819;37856.242875142896;39838;40205.597944020185;40525.39211911765;40840.97897552466;29647;29772;30197.69802301949;30476.695233047387;30550.694493054783 16:46:57 INFO - PROCESS | 18463 | |2;jquery.html;413.4914055711473;416.8811888611746;422.6344212256398;428.24414907687924;448.9349044388564;19592.314269000588;20661.896690516565;21137.788622113774;21293.25473608424;21460.89269553654;16732.16339183042;19338;19984.60030799383;20475.692864607005;20672.379828605128;5769.04810706233;6037.366076561963;6042.425969532909;6042.818715438544;6048.092786082079;5757.827265182052;5932.110183472492;6015.578909476339;6034.4568988791;6073.362296958822;5986.610870293439;5996.400359964013;6016.458518733313;6017.428344307305;6027.849303767397;5911.852203694899;6542.41118299353;6649.534532582721;6661.26726060133;6707.631080290582;5695.601307908448;5885.293764748215;5907.320658124285;5945.346011938684;5974.581779275452;13684.384202710802;13725.45098196071;14308;14348.784768228485;14586.562403127924;2545.8345207561633;2550.7321731218326;2684.691260505028;2686.6507354043847;3129.4992801151707;16425.26086326118;16565.6686866262;17001.319947202097;17274.395396161075;18292.45122646323;2191.41927389242;2204.680321353395;2299.3331933739196;2374.893129809163;2389.5340408620327;93705;96540.51729741377;98202;98219.50890245574;101646.49176754069;18663.346782862536;19035.428937131906;19062.904685476486;19071.713924291147;19229.615407691766;34650.653493465245;34956.47565286523;34979.650203498146;35000.12499687477;35338.82330588382;10723.517441715141;10871.184661150446;10881.12950963921;11085.722856928642;11280.928311810365;9010.819783604291;9082.772930676785;9105.043970383147;9116.1339672731;9382.24942004638;9145.679901203426;9402.247820174369;9563.282753793354;9625.807483850283;9685.17675997534;3239.6760323967414;3466.9826650866585;3608.657177568127;3618.1678214010926;3740.775553466801;976.6728146071003;1046.5918291866026;1065.046783129095;1067.3915867955345;1077.1490522487147;20618.896905515678;21261.787382125982;21289.78710212909;21305.041273142746;21389.67915481238;1073.4042606353548;1074.2909679611419;1084.9620263290747;1090.7164137324353;1104.6520346090956;11439.771204575862;11617.244879082833;11672.20111486725;11789.528418863321;11800.645980620595;556603;564107;570091;570885;582531;87331;91215;91425.54287228521;91866;92483;1027.3476342522404;1032.5301987595626;1065.6856227412932;1068.4871261794383;1071.2073065931154;18153.27386904509;18538.814611853708;18665;18892;19228.61542769109;45028;45028;46326.76836615795;47044.82387940329;47561.286580702006;57900.710496447246;58037;61930.69034654978;62633.68683156555;63916;5864.9413505866105;7760.068791745027;8530.10433904431;8788.209061184523;8827.514486703292;12641.115121941375;14024.64938376508;14099.718005640036;14522.564323070324;14883.627909302355;12249.448774804978;12315.13794034409;12450.128491005906;12632.810507842207;12642.597906397756;11516.53933842637;12036.879631203927;12314.568990085305;12362.134650574373;12678.112532123032;12508.499660013498;12536.247825130522;12629;12669.303188324731;12744.605122333453;8895.288376929959;9026.18764311215;9135.182852438507;9290.674826381044;12395.987945846438;11984.64046078619;12130.818037729618;12795.232286062866;12941.870581294066;13389.732205355644 16:46:57 INFO - PROCESS | 18463 | |3;mootools.html;1349.25116560309;1517.8482151784822;1559.547731157964;1689.5776055986003;1863.1057092595554;8333.958330208348;8720.869186962193;8780.078091800357;9003.279737620991;9026.90973090269;8806.735797926056;8906;8932;8936.508492032935;8941.41880777749;2436.5979613363775;2454.7790698837102;2459.372859920718;2462.4582591829767;2463.458039231375;2374.5630625724125;2498.71264804548;2503.173952595648;2510.983051863998;2514.3085651445817;2357.422431504275;2374.536965291769;2408.494216214597;2409.6144616861307;2410.4817464245184;2334.9066037358484;2366.7633236676365;2370.7629237076153;2380.2859142257425;2381.3451300892307;2356.740758516562;2388.677528533643;2391.210900402863;2396.628522579003;2398.9880050599636;8598.355123365773;8910.064443233432;8944.910550894472;9001.369904106716;9034.774130646721;5198.9480105198845;5339.145736682112;5343.145096784496;5345.572354211654;5357.303550538444;3479.0606536235327;3484.059303987909;3575.3206890690685;3587.3542762302777;3602.4596310553366;1157.4502111497056;1177.7821103095955;1194.6714653470278;1197.658667279821;1199.7780410624061;12065.577704780291;12076.335801530913;12099.637010889599;12134.150609457341;12148.271103733805;4406.325596273396;4530.139273538017;4642.605378542794;4643.419572553431;4651.162790697707;9644.855327170098;9654.27592930533;9662.613495460244;9665.613375464902;9665.951670241604;5326.44072372403;5343.492368225013;5348.759305831247;5351.1973204019705;5365.141577347606;4350.9564904350555;4707.129181101507;4868.537488938545;4918.483559226302;4946.826861059845;3881.2237552489614;3909.198614284065;3985.5815139409942;4039.313316736162;4045.0089728016824;1188.2157775867886;1188.95838645647;1190.6428071578491;1197.4970512384818;1233.0998371189082;990.5988074829705;994.5425104452033;1012.7974405119006;1024.538957469142;1040.105509262034;5932.555058370639;5939;6096.756129754849;6154.661493617894;6219.222597175353;458.9265717485253;459.53127809633986;461.4347424405077;469.0806020200445;474.3975151557503;4152.854650087233;4169.937450938239;4223.408722778813;4360.738355698668;4460.241758900995;11140.33158010522;11219.270747401393;11328.830067548994;11331.43342832855;11358;10999.065079468335;11000.614978475714;11097.38964356968;11102.500387482583;11107.611233606785;7979.760607181794;8233.50598964064;8342.499450032896;8352.916470835335;8459.196376344238;6022.789202377896;6043.09353596964;6140.8771824564155;6199.845003874939;6224.564280500503;10349.275550711378;10464.47677616101;10469.162467002774;10470.738231544272;10474.528646210787;9984.500774961369;10420.32267902584;10426.113780328453;10559.841602376124;10647.148228141576;7359.1169059713175;7830.099538552911;7966.601669916365;8005.8398832024195;8131.634076466455;8714.346424018097;8907.10928907104;8908.55457227149;8911.287097032093;8912.821743564962;7628.122765881994;7675.731349402744;7788.143304236416;7872.685092596232;8157.55133467665;7524.322810947041;7633.847323053619;7683.807904802425;7762.223777622193;7791;7045.753398631024;7128.964355178398;7129.180144283474;7137;7140.07179066718;5128.666636668605;5185.170372740344;5204.34945631796;5205.349331333583;5348.652337598043;5266.736663166903;5276.551493123127;5279.524842764016;5297.099493086108;5422.403535611158 16:46:57 INFO - PROCESS | 18463 | |4;prototype.html;338.7916431394692;345.21808104642975;346.7988566631354;360.9765365251257;361.6979821848756;5483.972580137094;5492.505674489295;5515.806946756864;5519.17212418137;5519.779208831652;5188.143956247215;5352.652077614952;5364.222187782769;5386.596005299599;5441.782328706857;4173.645240154576;4243.957560424395;4290.1848648756795;4341.6092551670345;4344.934825977597;4307.578499095307;4322.935155972647;4332.740035597859;4333.891652708692;4339.088791353819;4088.6115818997146;4267.594578515036;4272.248378164953;4277.679174061942;4292.399064131024;4228.682848786322;4246.384274280227;4252.362145678142;4261.147770445908;4264.253755592759;3771.547414310273;3772.830222639988;3776.981115094407;3779.3764028935193;3785.5078839750654;5224;5324.440933701944;5329.387120481156;5362.544183744387;5369.328833895763;4876.244182151772;4880.877978050542;4893.853184404474;4894.559489645913;4901.534354236342;5679;5682.147677848315;5726.627769195031;5727.627704199213;5759.481646651821;4029.9395509067385;4080.6125917188297;4123;4124.6906482013965;4131.5248746394245;5894.292684877799;5919.987204350541;5922.4077592240865;5930.7924222652;5949.851253718605;4213.346931225665;4227.070044590185;4230.1539692061715;4230.238557059759;4235.131797981407;4198.6641068714425;4295.29127693927;4297.828086876552;4372.868813935587;4431.401760762255;4953.678010929278;4963.70217786934;4967.466592038087;4973.1297023020825;4977.651564390458;5744.793593345445;5749.626274292157;5765.414511009506;5772.682502462321;5787.672088549101;1489.255372313843;1523.8933274670667;1532.524917275648;1534.7467667834906;1550.5813430373737;981.1218959031627;995.1988649137369;995.9651412200539;1004.2217281606813;1005.934614250086;4910.975445122752;4936.753162341868;4978.278149668277;4984.27727979441;4995.250712393097;5211.530962213419;5215;5219.921701174486;5220.851412689202;5228.980348832018;4755.096531658974;4944.975275123673;4950.925736113961;4987.476314986947;4999.225120106389;2745.697973222944;2774.4451109778124;2774.625425567563;2790.9023184188454;2800.1879454958244;5271.156614941668;5295.973520132375;5307.893842123136;5331.3868905076415;5333.359996800331;4948.480409556944;5229.398619158769;5251.84889066557;5274.815381461631;5295.523402893681;5244.895102097937;5301.655392399559;5320.52115309624;5382.892342153135;5385.434529374359;5137.974310128426;5189.169732842727;5203.19350500673;5205.609579281645;5223.582113430993;5166.689998600096;5447.645903016291;5838.737256823369;5862.866678991216;5885.969955257899;6160.507159427146;6169.290531588925;6171.105189747372;6177.505799535937;6180.876382472416;4153.217344379213;4267.359896015499;4274.610751505711;4275.87172384829;4281.250781113355;5851.561132914963;5925.259342582177;5929.6145750526075;5935.080062590306;5943.1679564861815;5757.971210143923;5817.476427121579;5825.291239573449;5832.533397328119;5839.649621022752;5648.181013753065;5761.596688231783;5807.622504537191;5812.82561523155;5819.806939577461;5718.199452076629;5719;5725.885482290414;5729.598928075162;5751.194832723339;5537.944620553743;5729.197912292199;5761.098837383737;5768.048272035067;5772.9711351442975;5636.520895723909;5658.943410565842;5659;5665.348484924121;5668.37647858727 16:46:57 INFO - PROCESS | 18463 | |5;yui.html;759.7568777991045;784.4587234807982;795.2921899509438;858.5664239559022;861.3970220845406;14060.437582496688;14188.43246270148;14223.43106275749;14856.999999999987;15142.242887855618;14120.505782297596;14257.50098746544;14270.215138167423;14278.357473913673;14286.428542858299;728.6830228850467;848.2069265237024;856.6402111113332;872.4285592936636;885.2254277507182;837.271573730855;839.5088873009297;844.4342290665238;844.5903736687701;844.8183640517287;912.4890061565544;912.9315301352392;916.3493919317259;916.9954150229239;917.4816228830723;911.9361644684875;912.9224015958652;913.0276255787599;913.0732306708696;913.8903331600184;670.8859493886002;672.4116398151617;672.6065251827687;672.7309076369443;672.9730810767563;14081.507147249797;14085.788713169311;14105.506307279298;14115.435382584685;14116.588341165903;14340.856591434056;14404.279786010658;14533.34599943005;16433.91783041077;17113.828861711474;13123.212607243597;13150.276734779583;13247.536336228186;13389.397477113553;13984.16095034291;4769.093872164278;4783.139034973703;4788.3056956741075;4829.830955916561;4951.727654978975;67781;70027;79929.60035199845;80347.5982620089;80535.19464805335;11940.52237910474;12030.578929737418;12037.277763334228;12372.1958072725;12525.498980040697;22929.656055159187;23197;23248;23256.4185895354;23260.069597215923;8957.372983891195;9029.187373136449;9131.223845973034;9361.110694484014;9434.433933963985;4935.728534930613;5069.594432445396;5114.769835357418;5210.583153347723;5295.126304159848;3641.6904563111902;3757.492738480325;3778.282126395976;3780.2250538639514;3785.9242815144094;641.5797652537595;646.0890144895675;656.7766959233884;663.4924282923567;674.2583158525582;651.5374084400034;713.6396119959387;766.3524321947945;767.1063211358816;784.6155383861867;9701.51492425376;9901.257405694601;10261.538230779634;10349.689509314732;10464.581416743396;709.6770969208998;720.6468830273233;735.1692587376338;747.5589402252697;775.6354513378703;4641.651876109306;4861.805527778849;4876.926846097312;4877.609791216694;5051.217061355495;134492;134668;134797;135547.32226338805;136649;65557.67221163864;66606.66696666485;105436.47281763695;119368;119832.40083799526;100842;102050.48974755079;104141.47929260305;104147;108091;9269.582868771056;9564.713058608253;9617.759556011155;9673.613055477703;9901.257405694458;47976.520234798605;48479.5152048475;59499;60920;61034;75850;76047.85928210971;77756.61121694582;78252.60873695595;78886.6055669718;12939.223646581235;13014.739705206031;13093.148945318524;13184.934075329946;13460.730785384041;14730.55808325752;14956.326965286371;15246.3901443941;16218.513444596681;17062;13672.93163534176;14247.786283205553;14354.066985645899;14942.551723448314;15764.76352854731;12792.680182995127;12878.227306361649;13359.064865459324;13395.263260520765;13474.663133421744;11857.644270671894;12480.25118492857;12815;12907.161034532724;13150.868491315347;11380.486064306242;11675.824862626898;11992.940035299767;12138.575149869712;12415.37923103823;11742.236754610924;11918.523259069887;12156.513739450324;12327;12603.432845521791 16:46:57 INFO - PROCESS | 18463 | __end_tp_report 16:46:57 INFO - PROCESS | 18463 | __start_cc_report 16:46:57 INFO - PROCESS | 18463 | _x_x_mozilla_cycle_collect,924 16:46:57 INFO - PROCESS | 18463 | __end_cc_report 16:46:57 INFO - PROCESS | 18463 | __startTimestamp1478389617734__endTimestamp 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | ------- Summary: start ------- 16:46:57 INFO - PROCESS | 18463 | Number of tests: 6 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#0] dojo.html Cycles:165 Average:5621.18 Median:5836.33 stddev:642.83 (11.0%) stddev-sans-first:616.51 16:46:57 INFO - PROCESS | 18463 | Values: 3217.2 3221.3 3225.6 3259.5 3285.2 5594.1 5698.2 5699.0 5709.1 5731.6 5656.2 5708.3 5712.8 5716.8 5733.4 5149.0 5159.2 5164.4 5222.2 5224.9 5071.0 5228.7 5244.8 5251.8 5277.6 5039.2 5072.3 5093.1 5116.1 5151.8 5069.5 5104.3 5205.3 5208.0 5245.3 4958.7 5013.7 5068.5 5144.8 5167.3 5517.0 5523.4 5523.9 5545.6 5598.6 5937.0 5977.5 6035.5 6038.1 6042.2 5886.7 6007.7 6066.2 6076.6 6080.2 5560.5 5624.4 5640.4 5647.9 5730.9 6251.9 6260.2 6260.9 6265.4 6268.7 5730.2 5761.4 5980.2 5987.8 5988.5 5954.6 5955.1 5998.5 6046.1 6047.8 6065.6 6074.8 6084.3 6094.0 6113.0 5976.9 6021.8 6087.0 6098.5 6099.3 4472.1 4552.7 4556.5 4672.4 4682.1 4110.5 4116.9 4128.5 4129.0 4159.9 5721.9 5724.4 5726.3 5730.6 5733.6 5892.7 5998.1 6051.2 6055.9 6059.7 5739.3 5798.2 5802.6 5808.5 5809.2 5292.0 5297.8 5306.4 5308.0 5309.2 6040.9 6117.1 6172.3 6313.7 6323.9 6067.5 6083.5 6089.4 6111.8 6127.4 6192.7 6231.6 6255.7 6269.2 6271.6 5771.6 5894.4 5910.2 5944.3 5973.8 6110.6 6124.8 6125.6 6127.6 6127.8 5989.1 5991.1 5992.5 5993.8 6020.5 5803.2 5841.9 5883.3 5912.5 5981.5 5889.7 5897.1 5901.4 5912.7 5933.2 5935.6 5968.1 5975.6 5978.6 6017.0 5661.3 5687.6 5773.1 5836.3 5840.0 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#1] ext.html Cycles:180 Average:64754.35 Median:22108.94 stddev:136241.64 (616.2%) stddev-sans-first:136538.67 16:46:57 INFO - PROCESS | 18463 | Values: 593.9 641.3 666.5 668.8 670.4 33860.3 44831.8 45710.0 45711.5 45744.8 48481.0 48980.8 48987.3 48988.3 49017.5 8436.9 9220.2 9338.7 9416.2 9416.7 10733.7 11009.7 11016.3 11411.3 11417.1 11172.6 11272.3 11275.7 11288.0 11288.1 11098.5 11116.6 11135.2 11148.6 11190.9 10936.5 11089.2 11102.3 11105.8 11108.2 45400.0 45614.3 45744.5 45761.0 45770.1 3730.5 4012.3 4158.6 4208.7 4647.3 34999.8 35045.8 35050.8 35216.3 35248.0 4544.5 4557.6 4604.5 4698.0 4706.6 242774.0 242897.8 243768.0 244711.0 246858.0 63871.0 64180.0 64208.7 64267.7 64340.7 39625.4 40260.6 40323.2 42288.8 67363.0 19324.4 19479.2 19534.2 19537.1 19543.0 13179.6 14739.2 14965.4 15025.7 15105.2 21032.0 21227.5 21233.4 21318.3 21327.6 7456.3 7533.9 7602.2 7615.4 7702.2 1053.8 1062.8 1120.7 1121.5 1198.8 34570.0 34694.8 34695.7 35234.8 35280.8 1166.5 1192.7 1194.5 1273.4 1329.7 18538.0 18597.8 18654.4 18737.6 18788.2 695866.0 702589.0 706389.0 706492.0 707878.0 63972.0 64392.7 64769.0 64807.0 64828.4 1369.7 1468.9 1476.1 1506.0 1508.1 33823.0 35028.3 35137.3 35349.3 35375.0 54612.5 54808.2 55095.7 55297.4 55450.0 469142.0 470551.0 471025.0 471320.0 474926.0 27523.2 29978.4 30612.7 30746.4 31061.0 51769.7 51864.0 52714.8 52799.0 57354.4 45287.0 45736.8 46211.5 46475.5 46970.8 43741.9 45823.5 45855.8 46411.5 47400.3 45165.5 45463.5 45517.8 45571.0 45819.0 37856.2 39838.0 40205.6 40525.4 40841.0 29647.0 29772.0 30197.7 30476.7 30550.7 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#2] jquery.html Cycles:180 Average:33200.63 Median:2467.68 stddev:93495.49 (3788.8%) stddev-sans-first:93725.36 16:46:57 INFO - PROCESS | 18463 | Values: 413.5 416.9 422.6 428.2 448.9 19592.3 20661.9 21137.8 21293.3 21460.9 16732.2 19338.0 19984.6 20475.7 20672.4 5769.0 6037.4 6042.4 6042.8 6048.1 5757.8 5932.1 6015.6 6034.5 6073.4 5986.6 5996.4 6016.5 6017.4 6027.8 5911.9 6542.4 6649.5 6661.3 6707.6 5695.6 5885.3 5907.3 5945.3 5974.6 13684.4 13725.5 14308.0 14348.8 14586.6 2545.8 2550.7 2684.7 2686.7 3129.5 16425.3 16565.7 17001.3 17274.4 18292.5 2191.4 2204.7 2299.3 2374.9 2389.5 93705.0 96540.5 98202.0 98219.5 101646.5 18663.3 19035.4 19062.9 19071.7 19229.6 34650.7 34956.5 34979.7 35000.1 35338.8 10723.5 10871.2 10881.1 11085.7 11280.9 9010.8 9082.8 9105.0 9116.1 9382.2 9145.7 9402.2 9563.3 9625.8 9685.2 3239.7 3467.0 3608.7 3618.2 3740.8 976.7 1046.6 1065.0 1067.4 1077.1 20618.9 21261.8 21289.8 21305.0 21389.7 1073.4 1074.3 1085.0 1090.7 1104.7 11439.8 11617.2 11672.2 11789.5 11800.6 556603.0 564107.0 570091.0 570885.0 582531.0 87331.0 91215.0 91425.5 91866.0 92483.0 1027.3 1032.5 1065.7 1068.5 1071.2 18153.3 18538.8 18665.0 18892.0 19228.6 45028.0 45028.0 46326.8 47044.8 47561.3 57900.7 58037.0 61930.7 62633.7 63916.0 5864.9 7760.1 8530.1 8788.2 8827.5 12641.1 14024.6 14099.7 14522.6 14883.6 12249.4 12315.1 12450.1 12632.8 12642.6 11516.5 12036.9 12314.6 12362.1 12678.1 12508.5 12536.2 12629.0 12669.3 12744.6 8895.3 9026.2 9135.2 9290.7 12396.0 11984.6 12130.8 12795.2 12941.9 13389.7 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#3] mootools.html Cycles:180 Average:5866.58 Median:2560.12 stddev:3325.65 (129.9%) stddev-sans-first:3317.65 16:46:57 INFO - PROCESS | 18463 | Values: 1349.3 1517.8 1559.5 1689.6 1863.1 8334.0 8720.9 8780.1 9003.3 9026.9 8806.7 8906.0 8932.0 8936.5 8941.4 2436.6 2454.8 2459.4 2462.5 2463.5 2374.6 2498.7 2503.2 2511.0 2514.3 2357.4 2374.5 2408.5 2409.6 2410.5 2334.9 2366.8 2370.8 2380.3 2381.3 2356.7 2388.7 2391.2 2396.6 2399.0 8598.4 8910.1 8944.9 9001.4 9034.8 5198.9 5339.1 5343.1 5345.6 5357.3 3479.1 3484.1 3575.3 3587.4 3602.5 1157.5 1177.8 1194.7 1197.7 1199.8 12065.6 12076.3 12099.6 12134.2 12148.3 4406.3 4530.1 4642.6 4643.4 4651.2 9644.9 9654.3 9662.6 9665.6 9666.0 5326.4 5343.5 5348.8 5351.2 5365.1 4351.0 4707.1 4868.5 4918.5 4946.8 3881.2 3909.2 3985.6 4039.3 4045.0 1188.2 1189.0 1190.6 1197.5 1233.1 990.6 994.5 1012.8 1024.5 1040.1 5932.6 5939.0 6096.8 6154.7 6219.2 458.9 459.5 461.4 469.1 474.4 4152.9 4169.9 4223.4 4360.7 4460.2 11140.3 11219.3 11328.8 11331.4 11358.0 10999.1 11000.6 11097.4 11102.5 11107.6 7979.8 8233.5 8342.5 8352.9 8459.2 6022.8 6043.1 6140.9 6199.8 6224.6 10349.3 10464.5 10469.2 10470.7 10474.5 9984.5 10420.3 10426.1 10559.8 10647.1 7359.1 7830.1 7966.6 8005.8 8131.6 8714.3 8907.1 8908.6 8911.3 8912.8 7628.1 7675.7 7788.1 7872.7 8157.6 7524.3 7633.8 7683.8 7762.2 7791.0 7045.8 7129.0 7129.2 7137.0 7140.1 5128.7 5185.2 5204.3 5205.3 5348.7 5266.7 5276.6 5279.5 5297.1 5422.4 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#4] prototype.html Cycles:180 Average:4705.61 Median:5217.46 stddev:1356.38 (26.0%) stddev-sans-first:1319.99 16:46:57 INFO - PROCESS | 18463 | Values: 338.8 345.2 346.8 361.0 361.7 5484.0 5492.5 5515.8 5519.2 5519.8 5188.1 5352.7 5364.2 5386.6 5441.8 4173.6 4244.0 4290.2 4341.6 4344.9 4307.6 4322.9 4332.7 4333.9 4339.1 4088.6 4267.6 4272.2 4277.7 4292.4 4228.7 4246.4 4252.4 4261.1 4264.3 3771.5 3772.8 3777.0 3779.4 3785.5 5224.0 5324.4 5329.4 5362.5 5369.3 4876.2 4880.9 4893.9 4894.6 4901.5 5679.0 5682.1 5726.6 5727.6 5759.5 4029.9 4080.6 4123.0 4124.7 4131.5 5894.3 5920.0 5922.4 5930.8 5949.9 4213.3 4227.1 4230.2 4230.2 4235.1 4198.7 4295.3 4297.8 4372.9 4431.4 4953.7 4963.7 4967.5 4973.1 4977.7 5744.8 5749.6 5765.4 5772.7 5787.7 1489.3 1523.9 1532.5 1534.7 1550.6 981.1 995.2 996.0 1004.2 1005.9 4911.0 4936.8 4978.3 4984.3 4995.3 5211.5 5215.0 5219.9 5220.9 5229.0 4755.1 4945.0 4950.9 4987.5 4999.2 2745.7 2774.4 2774.6 2790.9 2800.2 5271.2 5296.0 5307.9 5331.4 5333.4 4948.5 5229.4 5251.8 5274.8 5295.5 5244.9 5301.7 5320.5 5382.9 5385.4 5138.0 5189.2 5203.2 5205.6 5223.6 5166.7 5447.6 5838.7 5862.9 5886.0 6160.5 6169.3 6171.1 6177.5 6180.9 4153.2 4267.4 4274.6 4275.9 4281.3 5851.6 5925.3 5929.6 5935.1 5943.2 5758.0 5817.5 5825.3 5832.5 5839.6 5648.2 5761.6 5807.6 5812.8 5819.8 5718.2 5719.0 5725.9 5729.6 5751.2 5537.9 5729.2 5761.1 5768.0 5773.0 5636.5 5658.9 5659.0 5665.3 5668.4 16:46:57 INFO - PROCESS | 18463 | 16:46:57 INFO - PROCESS | 18463 | [#5] yui.html Cycles:180 Average:22164.70 Median:4213.79 stddev:33134.72 (786.3%) stddev-sans-first:33188.70 16:46:57 INFO - PROCESS | 18463 | Values: 759.8 784.5 795.3 858.6 861.4 14060.4 14188.4 14223.4 14857.0 15142.2 14120.5 14257.5 14270.2 14278.4 14286.4 728.7 848.2 856.6 872.4 885.2 837.3 839.5 844.4 844.6 844.8 912.5 912.9 916.3 917.0 917.5 911.9 912.9 913.0 913.1 913.9 670.9 672.4 672.6 672.7 673.0 14081.5 14085.8 14105.5 14115.4 14116.6 14340.9 14404.3 14533.3 16433.9 17113.8 13123.2 13150.3 13247.5 13389.4 13984.2 4769.1 4783.1 4788.3 4829.8 4951.7 67781.0 70027.0 79929.6 80347.6 80535.2 11940.5 12030.6 12037.3 12372.2 12525.5 22929.7 23197.0 23248.0 23256.4 23260.1 8957.4 9029.2 9131.2 9361.1 9434.4 4935.7 5069.6 5114.8 5210.6 5295.1 3641.7 3757.5 3778.3 3780.2 3785.9 641.6 646.1 656.8 663.5 674.3 651.5 713.6 766.4 767.1 784.6 9701.5 9901.3 10261.5 10349.7 10464.6 709.7 720.6 735.2 747.6 775.6 4641.7 4861.8 4876.9 4877.6 5051.2 134492.0 134668.0 134797.0 135547.3 136649.0 65557.7 66606.7 105436.5 119368.0 119832.4 100842.0 102050.5 104141.5 104147.0 108091.0 9269.6 9564.7 9617.8 9673.6 9901.3 47976.5 48479.5 59499.0 60920.0 61034.0 75850.0 76047.9 77756.6 78252.6 78886.6 12939.2 13014.7 13093.1 13184.9 13460.7 14730.6 14956.3 15246.4 16218.5 17062.0 13672.9 14247.8 14354.1 14942.6 15764.8 12792.7 12878.2 13359.1 13395.3 13474.7 11857.6 12480.3 12815.0 12907.2 13150.9 11380.5 11675.8 11992.9 12138.6 12415.4 11742.2 11918.5 12156.5 12327.0 12603.4 16:46:57 INFO - PROCESS | 18463 | -------- Summary: end -------- 16:46:57 INFO - PROCESS | 18463 | 16:46:58 INFO - TEST-INFO | 18463: exit 0 16:46:58 INFO - TEST-OK | dromaeo_css | took 1112763ms 16:46:58 INFO - TEST-START | kraken 16:46:58 INFO - operating with platform_type : linux_ 16:46:58 INFO - Initialising browser for kraken test... 16:46:58 INFO - TEST-INFO | started process 18657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp36_MM2/profile http://localhost:55623/getInfo.html) 16:47:02 INFO - PROCESS | 18657 | __metrics Screen width/height:1600/1200 16:47:02 INFO - PROCESS | 18657 | colorDepth:24 16:47:02 INFO - PROCESS | 18657 | Browser inner width/height: 1024/697 16:47:02 INFO - PROCESS | 18657 | __metrics 16:47:03 INFO - PROCESS | 18657 | 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:03 INFO - PROCESS | 18657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:04 INFO - TEST-INFO | 18657: exit 0 16:47:04 INFO - Browser initialized. 16:47:04 INFO - Running cycle 1/1 for kraken test... 16:47:04 INFO - TEST-INFO | started process 18746 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp36_MM2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:15 INFO - PROCESS | 18746 | RSS: Main: 144363520 16:47:15 INFO - PROCESS | 18746 | 16:47:18 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55623/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:19 INFO - PROCESS | 18746 | RSS: Main: 199507968 16:47:19 INFO - PROCESS | 18746 | 16:47:21 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55623/tests/kraken/driver.html?testName=audio-dft) 16:47:22 INFO - PROCESS | 18746 | RSS: Main: 184016896 16:47:22 INFO - PROCESS | 18746 | 16:47:25 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55623/tests/kraken/driver.html?testName=audio-fft) 16:47:25 INFO - PROCESS | 18746 | RSS: Main: 275234816 16:47:25 INFO - PROCESS | 18746 | 16:47:27 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55623/tests/kraken/driver.html?testName=audio-oscillator) 16:47:28 INFO - PROCESS | 18746 | RSS: Main: 180367360 16:47:28 INFO - PROCESS | 18746 | 16:47:30 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55623/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:31 INFO - PROCESS | 18746 | RSS: Main: 179699712 16:47:31 INFO - PROCESS | 18746 | 16:47:34 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55623/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:34 INFO - PROCESS | 18746 | RSS: Main: 215650304 16:47:34 INFO - PROCESS | 18746 | 16:47:38 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55623/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:38 INFO - PROCESS | 18746 | RSS: Main: 214405120 16:47:38 INFO - PROCESS | 18746 | 16:47:41 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55623/tests/kraken/driver.html?testName=json-parse-financial) 16:47:42 INFO - PROCESS | 18746 | RSS: Main: 214355968 16:47:42 INFO - PROCESS | 18746 | 16:47:43 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55623/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:44 INFO - PROCESS | 18746 | RSS: Main: 176816128 16:47:44 INFO - PROCESS | 18746 | 16:47:45 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:46 INFO - PROCESS | 18746 | RSS: Main: 183599104 16:47:46 INFO - PROCESS | 18746 | 16:47:48 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:49 INFO - PROCESS | 18746 | RSS: Main: 189915136 16:47:49 INFO - PROCESS | 18746 | 16:47:51 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:52 INFO - PROCESS | 18746 | RSS: Main: 198021120 16:47:52 INFO - PROCESS | 18746 | 16:47:54 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:55 INFO - PROCESS | 18746 | RSS: Main: 195059712 16:47:55 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | Cycle 1(1): loaded http://localhost:55623/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55623/tests/kraken/driver.html?testName=ai-astar) 16:47:57 INFO - PROCESS | 18746 | RSS: Main: 246018048 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | __start_tp_report 16:47:57 INFO - PROCESS | 18746 | _x_x_mozilla_page_load 16:47:57 INFO - PROCESS | 18746 | _x_x_mozilla_page_load_details 16:47:57 INFO - PROCESS | 18746 | |i|pagename|runs| 16:47:57 INFO - PROCESS | 18746 | |0;ai-astar;111;111;121;109;109;108;109;110;111;110 16:47:57 INFO - PROCESS | 18746 | |1;audio-beat-detection;148;118;144;174;122;141;146;121;182;118 16:47:57 INFO - PROCESS | 18746 | |2;audio-dft;162;159;160;160;161;160;161;162;162;161 16:47:57 INFO - PROCESS | 18746 | |3;audio-fft;75;75;75;183;76;75;74;75;170;75 16:47:57 INFO - PROCESS | 18746 | |4;audio-oscillator;83;83;146;82;83;83;143;83;83;151 16:47:57 INFO - PROCESS | 18746 | |5;imaging-gaussian-blur;117;116;117;117;116;116;117;116;117;116 16:47:57 INFO - PROCESS | 18746 | |6;imaging-darkroom;147;147;146;147;147;147;146;147;146;146 16:47:57 INFO - PROCESS | 18746 | |7;imaging-desaturate;95;95;95;96;94;94;95;95;94;95 16:47:57 INFO - PROCESS | 18746 | |8;json-parse-financial;52;58;54;51;53;56;52;52;52;53 16:47:57 INFO - PROCESS | 18746 | |9;json-stringify-tinderbox;59;60;60;60;60;59;64;61;60;61 16:47:57 INFO - PROCESS | 18746 | |10;stanford-crypto-aes;73;76;79;75;74;81;73;73;80;78 16:47:57 INFO - PROCESS | 18746 | |11;stanford-crypto-ccm;119;123;122;127;117;128;118;120;162;120 16:47:57 INFO - PROCESS | 18746 | |12;stanford-crypto-pbkdf2;151;151;154;154;151;152;151;152;154;153 16:47:57 INFO - PROCESS | 18746 | |13;stanford-crypto-sha256-iterative;62;61;62;61;65;60;61;60;61;62 16:47:57 INFO - PROCESS | 18746 | __end_tp_report 16:47:57 INFO - PROCESS | 18746 | __start_cc_report 16:47:57 INFO - PROCESS | 18746 | _x_x_mozilla_cycle_collect,1694 16:47:57 INFO - PROCESS | 18746 | __end_cc_report 16:47:57 INFO - PROCESS | 18746 | __startTimestamp1478389677266__endTimestamp 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | ------- Summary: start ------- 16:47:57 INFO - PROCESS | 18746 | Number of tests: 14 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:3.70 (3.3%) stddev-sans-first:3.92 16:47:57 INFO - PROCESS | 18746 | Values: 111.0 111.0 121.0 109.0 109.0 108.0 109.0 110.0 111.0 110.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:145.00 stddev:22.78 (15.7%) stddev-sans-first:24.04 16:47:57 INFO - PROCESS | 18746 | Values: 148.0 118.0 144.0 174.0 122.0 141.0 146.0 121.0 182.0 118.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.00 16:47:57 INFO - PROCESS | 18746 | Values: 162.0 159.0 160.0 160.0 161.0 160.0 161.0 162.0 162.0 161.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#3] audio-fft Cycles:10 Average:95.30 Median:75.00 stddev:42.91 (57.2%) stddev-sans-first:44.88 16:47:57 INFO - PROCESS | 18746 | Values: 75.0 75.0 75.0 183.0 76.0 75.0 74.0 75.0 170.0 75.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:83.00 stddev:30.88 (37.2%) stddev-sans-first:31.98 16:47:57 INFO - PROCESS | 18746 | Values: 83.0 83.0 146.0 82.0 83.0 83.0 143.0 83.0 83.0 151.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:47:57 INFO - PROCESS | 18746 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:47:57 INFO - PROCESS | 18746 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 146.0 146.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:47:57 INFO - PROCESS | 18746 | Values: 95.0 95.0 95.0 96.0 94.0 94.0 95.0 95.0 94.0 95.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:2.16 (4.1%) stddev-sans-first:2.24 16:47:57 INFO - PROCESS | 18746 | Values: 52.0 58.0 54.0 51.0 53.0 56.0 52.0 52.0 52.0 53.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:1.43 (2.4%) stddev-sans-first:1.42 16:47:57 INFO - PROCESS | 18746 | Values: 59.0 60.0 60.0 60.0 60.0 59.0 64.0 61.0 60.0 61.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:77.00 stddev:3.08 (4.0%) stddev-sans-first:3.05 16:47:57 INFO - PROCESS | 18746 | Values: 73.0 76.0 79.0 75.0 74.0 81.0 73.0 73.0 80.0 78.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:122.50 stddev:13.29 (10.9%) stddev-sans-first:13.88 16:47:57 INFO - PROCESS | 18746 | Values: 119.0 123.0 122.0 127.0 117.0 128.0 118.0 120.0 162.0 120.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:152.50 stddev:1.34 (0.9%) stddev-sans-first:1.33 16:47:57 INFO - PROCESS | 18746 | Values: 151.0 151.0 154.0 154.0 151.0 152.0 151.0 152.0 154.0 153.0 16:47:57 INFO - PROCESS | 18746 | 16:47:57 INFO - PROCESS | 18746 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:1.43 (2.3%) stddev-sans-first:1.51 16:47:57 INFO - PROCESS | 18746 | Values: 62.0 61.0 62.0 61.0 65.0 60.0 61.0 60.0 61.0 62.0 16:47:57 INFO - PROCESS | 18746 | -------- Summary: end -------- 16:47:57 INFO - PROCESS | 18746 | 16:47:58 INFO - TEST-INFO | 18746: exit 0 16:47:58 INFO - TEST-OK | kraken | took 59795ms 16:47:58 INFO - SUITE-END | took 1172s 16:47:58 INFO - Completed test suite (00:19:32) 16:47:58 INFO - javascript benchmark 16:47:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.195701074731, 3221.3396253767974, 3225.629052658945, 3259.462188738858, 3285.2279714267142, 5594.132909399044, 5698.173764804106, 5698.971505142468, 5709.086546152616, 5731.598788084836, 5656.179853921179, 5708.257926469553, 5712.828615141542, 5716.8284951451415, 5733.426657334265, 5149.0, 5159.174532074869, 5164.354455693038, 5222.216667499886, 5224.92162617561, 5070.974645126769, 5228.660137091077, 5244.842654720345, 5251.842444726664, 5277.604179686519, 5039.21892106724, 5072.26452164436, 5093.134167191586, 5116.1302578561545, 5151.845444636667, 5069.543741063304, 5104.285400043997, 5205.349331333583, 5207.969241523951, 5245.31810864589, 4958.677685950402, 5013.749312534359, 5068.493150684902, 5144.768485418128, 5167.250748641463, 5517.034518959206, 5523.364813046511, 5523.889522209533, 5545.556355491551, 5598.608097433182, 5936.97031514844, 5977.521798256129, 6035.486983606399, 6038.064100064497, 6042.244719410074, 5886.70566471679, 6007.669578173199, 6066.211392518944, 6076.574639775174, 6080.179175811253, 5560.471755183251, 5624.35860392361, 5640.351359593617, 5647.943520564824, 5730.942690573041, 6251.874962500725, 6260.186175797117, 6260.937390626035, 6265.373462653789, 6268.686565671698, 5730.169125476783, 5761.3950535194035, 5980.162777211194, 5987.820365389045, 5988.490978266809, 5954.612950158225, 5955.0769630706445, 5998.460138587461, 6046.062860256668, 6047.848803779941, 6065.636061836304, 6074.75700971965, 6084.330723620398, 6093.969530152321, 6113.0, 5976.880462390728, 6021.849453763691, 6087.0, 6098.5426093042315, 6099.2680878294, 4472.105578884236, 4552.704074235164, 4556.453225612948, 4672.415948006499, 4682.1103990241745, 4110.547839737626, 4116.897077573085, 4128.545859955462, 4128.979355103205, 4159.8960025999595, 5721.914171287434, 5724.398938111438, 5726.341470730836, 5730.627509211888, 5733.569982251348, 5892.6759028253855, 5998.130271110752, 6051.213342265565, 6055.909161362583, 6059.666718330446, 5739.311282646109, 5798.246227990334, 5802.593818432753, 5808.477237048686, 5809.186713860063, 5292.047431462297, 5297.788088476418, 5306.363236411656, 5308.0, 5309.2301616266195, 6040.939590604038, 6117.096718007469, 6172.2901866284155, 6313.684315784192, 6323.936760632427, 6067.484263837623, 6083.513318934563, 6089.391060893875, 6111.755529778759, 6127.417895299939, 6192.659403732838, 6231.554598388639, 6255.749770009149, 6269.185005949197, 6271.592346497463, 5771.624844385102, 5894.410558944071, 5910.202122713378, 5944.256967879015, 5973.761049557969, 6110.6028108174605, 6124.838682352551, 6125.632272693224, 6127.617148554337, 6127.769884593299, 5989.071693887455, 5991.101334799815, 5992.490638295793, 5993.760249589967, 6020.458158765732, 5803.158542011469, 5841.883162336814, 5883.294004719461, 5912.46787789101, 5981.491573216325, 5889.676067816311, 5897.056470964625, 5901.350851406428, 5912.67480288571, 5933.228680271537, 5935.614185077956, 5968.104784282219, 5975.611585246945, 5978.641281523122, 6016.969915150396, 5661.264035675335, 5687.60186786921, 5773.1051686988585, 5836.328822185334, 5840.036393994943], "value": 5577.194943020905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.851537115721, 641.2753588445057, 666.5434177588351, 668.8060462465885, 670.3564578005112, 33860.32279354412, 44831.77584112079, 45710.0, 45711.54288457114, 45744.771276143656, 48481.0, 48980.75509622447, 48987.265191022074, 48988.26517602239, 49017.50982490174, 8436.915630843705, 9220.216281616054, 9338.673146439876, 9416.199623032051, 9416.717498475056, 10733.731656708567, 11009.724756881062, 11016.339019658846, 11411.31532108072, 11417.086633069375, 11172.608958686447, 11272.323660580392, 11275.66173014811, 11288.0, 11288.0969522438, 11098.500567474443, 11116.610918617891, 11135.220534562543, 11148.554057837677, 11190.944045279803, 10936.507857146447, 11089.168312376523, 11102.278351907182, 11105.83341249882, 11108.222424430292, 45400.0, 45614.31578526325, 45744.54255457469, 45761.0, 45770.08459830818, 3730.496382988289, 4012.277789997799, 4158.6465150462245, 4208.684348673831, 4647.279671650899, 34999.825000875084, 35045.82477087598, 35050.824745876365, 35216.29567408663, 35248.0, 4544.500104988449, 4557.56703113207, 4604.516525764813, 4697.976510117462, 4706.552877476634, 242774.0, 242897.78551107307, 243768.0, 244711.0, 246858.0, 63871.0, 64180.0, 64208.67895660492, 64267.67866160639, 64340.67829660822, 39625.40561891574, 40260.59739402569, 40323.19353612911, 42288.78855605702, 67363.0, 19324.420267392, 19479.220831166876, 19534.218631254877, 19537.12082956242, 19543.0, 13179.604611861469, 14739.189344585937, 14965.40138394452, 15025.699486010219, 15105.169215693022, 21032.0, 21227.46931326698, 21233.36299911005, 21318.253861115096, 21327.573448530944, 7456.32893039629, 7533.924660753432, 7602.163761986177, 7615.390768738488, 7702.152763196044, 1053.8155822730992, 1062.845887346346, 1120.7198200449889, 1121.484117306032, 1198.7602479504135, 34570.0, 34694.82652586721, 34695.65304346975, 35234.8238258807, 35280.82359588237, 1166.5217260922982, 1192.6839387562302, 1194.5281613762515, 1273.3569547378515, 1329.7274058817918, 18538.0, 18597.81402185988, 18654.36744744863, 18737.625247494972, 18788.154533046047, 695866.0, 702589.0, 706389.0, 706492.0, 707878.0, 63972.0, 64392.67803660952, 64769.0, 64807.0, 64828.35171648223, 1369.7260547890262, 1468.9265536723335, 1476.0774515927546, 1505.954821355361, 1508.057464084947, 33823.0, 35028.29943401067, 35137.29725405426, 35349.29301414009, 35375.0, 54612.453875460735, 54808.17787733107, 55095.72452137714, 55297.44702552923, 55450.0, 469142.0, 470551.0, 471025.0, 471320.0, 474926.0, 27523.174304770888, 29978.400431991675, 30612.693873060984, 30746.385072298875, 31061.0, 51769.741151294, 51864.0, 52714.837109773536, 52799.0, 57354.42645573491, 45287.0, 45736.771316143204, 46211.537884620724, 46475.535244648476, 46970.76514617405, 43741.856859063446, 45823.541764581925, 45855.77072114618, 46411.53588464072, 47400.28899566578, 45165.54834451614, 45463.545364545935, 45517.77241113773, 45571.0, 45819.0, 37856.242875142896, 39838.0, 40205.597944020185, 40525.39211911765, 40840.97897552466, 29647.0, 29772.0, 30197.69802301949, 30476.695233047387, 30550.694493054783], "value": 21951.04397870947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.4914055711473, 416.8811888611746, 422.6344212256398, 428.24414907687924, 448.9349044388564, 19592.314269000588, 20661.896690516565, 21137.788622113774, 21293.25473608424, 21460.89269553654, 16732.16339183042, 19338.0, 19984.60030799383, 20475.692864607005, 20672.379828605128, 5769.04810706233, 6037.366076561963, 6042.425969532909, 6042.818715438544, 6048.092786082079, 5757.827265182052, 5932.110183472492, 6015.578909476339, 6034.4568988791, 6073.362296958822, 5986.610870293439, 5996.400359964013, 6016.458518733313, 6017.428344307305, 6027.849303767397, 5911.852203694899, 6542.41118299353, 6649.534532582721, 6661.26726060133, 6707.631080290582, 5695.601307908448, 5885.293764748215, 5907.320658124285, 5945.346011938684, 5974.581779275452, 13684.384202710802, 13725.45098196071, 14308.0, 14348.784768228485, 14586.562403127924, 2545.8345207561633, 2550.7321731218326, 2684.691260505028, 2686.6507354043847, 3129.4992801151707, 16425.26086326118, 16565.6686866262, 17001.319947202097, 17274.395396161075, 18292.45122646323, 2191.41927389242, 2204.680321353395, 2299.3331933739196, 2374.893129809163, 2389.5340408620327, 93705.0, 96540.51729741377, 98202.0, 98219.50890245574, 101646.49176754069, 18663.346782862536, 19035.428937131906, 19062.904685476486, 19071.713924291147, 19229.615407691766, 34650.653493465245, 34956.47565286523, 34979.650203498146, 35000.12499687477, 35338.82330588382, 10723.517441715141, 10871.184661150446, 10881.12950963921, 11085.722856928642, 11280.928311810365, 9010.819783604291, 9082.772930676785, 9105.043970383147, 9116.1339672731, 9382.24942004638, 9145.679901203426, 9402.247820174369, 9563.282753793354, 9625.807483850283, 9685.17675997534, 3239.6760323967414, 3466.9826650866585, 3608.657177568127, 3618.1678214010926, 3740.775553466801, 976.6728146071003, 1046.5918291866026, 1065.046783129095, 1067.3915867955345, 1077.1490522487147, 20618.896905515678, 21261.787382125982, 21289.78710212909, 21305.041273142746, 21389.67915481238, 1073.4042606353548, 1074.2909679611419, 1084.9620263290747, 1090.7164137324353, 1104.6520346090956, 11439.771204575862, 11617.244879082833, 11672.20111486725, 11789.528418863321, 11800.645980620595, 556603.0, 564107.0, 570091.0, 570885.0, 582531.0, 87331.0, 91215.0, 91425.54287228521, 91866.0, 92483.0, 1027.3476342522404, 1032.5301987595626, 1065.6856227412932, 1068.4871261794383, 1071.2073065931154, 18153.27386904509, 18538.814611853708, 18665.0, 18892.0, 19228.61542769109, 45028.0, 45028.0, 46326.76836615795, 47044.82387940329, 47561.286580702006, 57900.710496447246, 58037.0, 61930.69034654978, 62633.68683156555, 63916.0, 5864.9413505866105, 7760.068791745027, 8530.10433904431, 8788.209061184523, 8827.514486703292, 12641.115121941375, 14024.64938376508, 14099.718005640036, 14522.564323070324, 14883.627909302355, 12249.448774804978, 12315.13794034409, 12450.128491005906, 12632.810507842207, 12642.597906397756, 11516.53933842637, 12036.879631203927, 12314.568990085305, 12362.134650574373, 12678.112532123032, 12508.499660013498, 12536.247825130522, 12629.0, 12669.303188324731, 12744.605122333453, 8895.288376929959, 9026.18764311215, 9135.182852438507, 9290.674826381044, 12395.987945846438, 11984.64046078619, 12130.818037729618, 12795.232286062866, 12941.870581294066, 13389.732205355644], "value": 10657.47829478105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.25116560309, 1517.8482151784822, 1559.547731157964, 1689.5776055986003, 1863.1057092595554, 8333.958330208348, 8720.869186962193, 8780.078091800357, 9003.279737620991, 9026.90973090269, 8806.735797926056, 8906.0, 8932.0, 8936.508492032935, 8941.41880777749, 2436.5979613363775, 2454.7790698837102, 2459.372859920718, 2462.4582591829767, 2463.458039231375, 2374.5630625724125, 2498.71264804548, 2503.173952595648, 2510.983051863998, 2514.3085651445817, 2357.422431504275, 2374.536965291769, 2408.494216214597, 2409.6144616861307, 2410.4817464245184, 2334.9066037358484, 2366.7633236676365, 2370.7629237076153, 2380.2859142257425, 2381.3451300892307, 2356.740758516562, 2388.677528533643, 2391.210900402863, 2396.628522579003, 2398.9880050599636, 8598.355123365773, 8910.064443233432, 8944.910550894472, 9001.369904106716, 9034.774130646721, 5198.9480105198845, 5339.145736682112, 5343.145096784496, 5345.572354211654, 5357.303550538444, 3479.0606536235327, 3484.059303987909, 3575.3206890690685, 3587.3542762302777, 3602.4596310553366, 1157.4502111497056, 1177.7821103095955, 1194.6714653470278, 1197.658667279821, 1199.7780410624061, 12065.577704780291, 12076.335801530913, 12099.637010889599, 12134.150609457341, 12148.271103733805, 4406.325596273396, 4530.139273538017, 4642.605378542794, 4643.419572553431, 4651.162790697707, 9644.855327170098, 9654.27592930533, 9662.613495460244, 9665.613375464902, 9665.951670241604, 5326.44072372403, 5343.492368225013, 5348.759305831247, 5351.1973204019705, 5365.141577347606, 4350.9564904350555, 4707.129181101507, 4868.537488938545, 4918.483559226302, 4946.826861059845, 3881.2237552489614, 3909.198614284065, 3985.5815139409942, 4039.313316736162, 4045.0089728016824, 1188.2157775867886, 1188.95838645647, 1190.6428071578491, 1197.4970512384818, 1233.0998371189082, 990.5988074829705, 994.5425104452033, 1012.7974405119006, 1024.538957469142, 1040.105509262034, 5932.555058370639, 5939.0, 6096.756129754849, 6154.661493617894, 6219.222597175353, 458.9265717485253, 459.53127809633986, 461.4347424405077, 469.0806020200445, 474.3975151557503, 4152.854650087233, 4169.937450938239, 4223.408722778813, 4360.738355698668, 4460.241758900995, 11140.33158010522, 11219.270747401393, 11328.830067548994, 11331.43342832855, 11358.0, 10999.065079468335, 11000.614978475714, 11097.38964356968, 11102.500387482583, 11107.611233606785, 7979.760607181794, 8233.50598964064, 8342.499450032896, 8352.916470835335, 8459.196376344238, 6022.789202377896, 6043.09353596964, 6140.8771824564155, 6199.845003874939, 6224.564280500503, 10349.275550711378, 10464.47677616101, 10469.162467002774, 10470.738231544272, 10474.528646210787, 9984.500774961369, 10420.32267902584, 10426.113780328453, 10559.841602376124, 10647.148228141576, 7359.1169059713175, 7830.099538552911, 7966.601669916365, 8005.8398832024195, 8131.634076466455, 8714.346424018097, 8907.10928907104, 8908.55457227149, 8911.287097032093, 8912.821743564962, 7628.122765881994, 7675.731349402744, 7788.143304236416, 7872.685092596232, 8157.55133467665, 7524.322810947041, 7633.847323053619, 7683.807904802425, 7762.223777622193, 7791.0, 7045.753398631024, 7128.964355178398, 7129.180144283474, 7137.0, 7140.07179066718, 5128.666636668605, 5185.170372740344, 5204.34945631796, 5205.349331333583, 5348.652337598043, 5266.736663166903, 5276.551493123127, 5279.524842764016, 5297.099493086108, 5422.403535611158], "value": 4630.650831377169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.7916431394692, 345.21808104642975, 346.7988566631354, 360.9765365251257, 361.6979821848756, 5483.972580137094, 5492.505674489295, 5515.806946756864, 5519.17212418137, 5519.779208831652, 5188.143956247215, 5352.652077614952, 5364.222187782769, 5386.596005299599, 5441.782328706857, 4173.645240154576, 4243.957560424395, 4290.1848648756795, 4341.6092551670345, 4344.934825977597, 4307.578499095307, 4322.935155972647, 4332.740035597859, 4333.891652708692, 4339.088791353819, 4088.6115818997146, 4267.594578515036, 4272.248378164953, 4277.679174061942, 4292.399064131024, 4228.682848786322, 4246.384274280227, 4252.362145678142, 4261.147770445908, 4264.253755592759, 3771.547414310273, 3772.830222639988, 3776.981115094407, 3779.3764028935193, 3785.5078839750654, 5224.0, 5324.440933701944, 5329.387120481156, 5362.544183744387, 5369.328833895763, 4876.244182151772, 4880.877978050542, 4893.853184404474, 4894.559489645913, 4901.534354236342, 5679.0, 5682.147677848315, 5726.627769195031, 5727.627704199213, 5759.481646651821, 4029.9395509067385, 4080.6125917188297, 4123.0, 4124.6906482013965, 4131.5248746394245, 5894.292684877799, 5919.987204350541, 5922.4077592240865, 5930.7924222652, 5949.851253718605, 4213.346931225665, 4227.070044590185, 4230.1539692061715, 4230.238557059759, 4235.131797981407, 4198.6641068714425, 4295.29127693927, 4297.828086876552, 4372.868813935587, 4431.401760762255, 4953.678010929278, 4963.70217786934, 4967.466592038087, 4973.1297023020825, 4977.651564390458, 5744.793593345445, 5749.626274292157, 5765.414511009506, 5772.682502462321, 5787.672088549101, 1489.255372313843, 1523.8933274670667, 1532.524917275648, 1534.7467667834906, 1550.5813430373737, 981.1218959031627, 995.1988649137369, 995.9651412200539, 1004.2217281606813, 1005.934614250086, 4910.975445122752, 4936.753162341868, 4978.278149668277, 4984.27727979441, 4995.250712393097, 5211.530962213419, 5215.0, 5219.921701174486, 5220.851412689202, 5228.980348832018, 4755.096531658974, 4944.975275123673, 4950.925736113961, 4987.476314986947, 4999.225120106389, 2745.697973222944, 2774.4451109778124, 2774.625425567563, 2790.9023184188454, 2800.1879454958244, 5271.156614941668, 5295.973520132375, 5307.893842123136, 5331.3868905076415, 5333.359996800331, 4948.480409556944, 5229.398619158769, 5251.84889066557, 5274.815381461631, 5295.523402893681, 5244.895102097937, 5301.655392399559, 5320.52115309624, 5382.892342153135, 5385.434529374359, 5137.974310128426, 5189.169732842727, 5203.19350500673, 5205.609579281645, 5223.582113430993, 5166.689998600096, 5447.645903016291, 5838.737256823369, 5862.866678991216, 5885.969955257899, 6160.507159427146, 6169.290531588925, 6171.105189747372, 6177.505799535937, 6180.876382472416, 4153.217344379213, 4267.359896015499, 4274.610751505711, 4275.87172384829, 4281.250781113355, 5851.561132914963, 5925.259342582177, 5929.6145750526075, 5935.080062590306, 5943.1679564861815, 5757.971210143923, 5817.476427121579, 5825.291239573449, 5832.533397328119, 5839.649621022752, 5648.181013753065, 5761.596688231783, 5807.622504537191, 5812.82561523155, 5819.806939577461, 5718.199452076629, 5719.0, 5725.885482290414, 5729.598928075162, 5751.194832723339, 5537.944620553743, 5729.197912292199, 5761.098837383737, 5768.048272035067, 5772.9711351442975, 5636.520895723909, 5658.943410565842, 5659.0, 5665.348484924121, 5668.37647858727], "value": 4282.525564165083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.7568777991045, 784.4587234807982, 795.2921899509438, 858.5664239559022, 861.3970220845406, 14060.437582496688, 14188.43246270148, 14223.43106275749, 14856.999999999987, 15142.242887855618, 14120.505782297596, 14257.50098746544, 14270.215138167423, 14278.357473913673, 14286.428542858299, 728.6830228850467, 848.2069265237024, 856.6402111113332, 872.4285592936636, 885.2254277507182, 837.271573730855, 839.5088873009297, 844.4342290665238, 844.5903736687701, 844.8183640517287, 912.4890061565544, 912.9315301352392, 916.3493919317259, 916.9954150229239, 917.4816228830723, 911.9361644684875, 912.9224015958652, 913.0276255787599, 913.0732306708696, 913.8903331600184, 670.8859493886002, 672.4116398151617, 672.6065251827687, 672.7309076369443, 672.9730810767563, 14081.507147249797, 14085.788713169311, 14105.506307279298, 14115.435382584685, 14116.588341165903, 14340.856591434056, 14404.279786010658, 14533.34599943005, 16433.91783041077, 17113.828861711474, 13123.212607243597, 13150.276734779583, 13247.536336228186, 13389.397477113553, 13984.16095034291, 4769.093872164278, 4783.139034973703, 4788.3056956741075, 4829.830955916561, 4951.727654978975, 67781.0, 70027.0, 79929.60035199845, 80347.5982620089, 80535.19464805335, 11940.52237910474, 12030.578929737418, 12037.277763334228, 12372.1958072725, 12525.498980040697, 22929.656055159187, 23197.0, 23248.0, 23256.4185895354, 23260.069597215923, 8957.372983891195, 9029.187373136449, 9131.223845973034, 9361.110694484014, 9434.433933963985, 4935.728534930613, 5069.594432445396, 5114.769835357418, 5210.583153347723, 5295.126304159848, 3641.6904563111902, 3757.492738480325, 3778.282126395976, 3780.2250538639514, 3785.9242815144094, 641.5797652537595, 646.0890144895675, 656.7766959233884, 663.4924282923567, 674.2583158525582, 651.5374084400034, 713.6396119959387, 766.3524321947945, 767.1063211358816, 784.6155383861867, 9701.51492425376, 9901.257405694601, 10261.538230779634, 10349.689509314732, 10464.581416743396, 709.6770969208998, 720.6468830273233, 735.1692587376338, 747.5589402252697, 775.6354513378703, 4641.651876109306, 4861.805527778849, 4876.926846097312, 4877.609791216694, 5051.217061355495, 134492.0, 134668.0, 134797.0, 135547.32226338805, 136649.0, 65557.67221163864, 66606.66696666485, 105436.47281763695, 119368.0, 119832.40083799526, 100842.0, 102050.48974755079, 104141.47929260305, 104147.0, 108091.0, 9269.582868771056, 9564.713058608253, 9617.759556011155, 9673.613055477703, 9901.257405694458, 47976.520234798605, 48479.5152048475, 59499.0, 60920.0, 61034.0, 75850.0, 76047.85928210971, 77756.61121694582, 78252.60873695595, 78886.6055669718, 12939.223646581235, 13014.739705206031, 13093.148945318524, 13184.934075329946, 13460.730785384041, 14730.55808325752, 14956.326965286371, 15246.3901443941, 16218.513444596681, 17062.0, 13672.93163534176, 14247.786283205553, 14354.066985645899, 14942.551723448314, 15764.76352854731, 12792.680182995127, 12878.227306361649, 13359.064865459324, 13395.263260520765, 13474.663133421744, 11857.644270671894, 12480.25118492857, 12815.0, 12907.161034532724, 13150.868491315347, 11380.486064306242, 11675.824862626898, 11992.940035299767, 12138.575149869712, 12415.37923103823, 11742.236754610924, 11918.523259069887, 12156.513739450324, 12327.0, 12603.432845521791], "value": 7941.442289294903, "unit": "score"}], "name": "dromaeo_css", "value": 7681.911842405812, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 121.0, 109.0, 109.0, 108.0, 109.0, 110.0, 111.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 144.0, 174.0, 122.0, 141.0, 146.0, 121.0, 182.0, 118.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 160.0, 160.0, 161.0, 160.0, 161.0, 162.0, 162.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 183.0, 76.0, 75.0, 74.0, 75.0, 170.0, 75.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 146.0, 82.0, 83.0, 83.0, 143.0, 83.0, 83.0, 151.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 54.0, 51.0, 53.0, 56.0, 52.0, 52.0, 52.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 64.0, 61.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 79.0, 75.0, 74.0, 81.0, 73.0, 73.0, 80.0, 78.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 123.0, 122.0, 127.0, 117.0, 128.0, 118.0, 120.0, 162.0, 120.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 154.0, 154.0, 151.0, 152.0, 151.0, 152.0, 154.0, 153.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 61.0, 65.0, 60.0, 61.0, 60.0, 61.0, 62.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1497.6, "alertThreshold": 2.0}]} 16:47:58 INFO - Return code: 0 16:47:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:58 INFO - # TBPL SUCCESS # 16:47:58 INFO - Running post-action listener: _resource_record_post_action 16:47:58 INFO - [mozharness: 2016-11-05 23:47:58.593912Z] Finished run-tests step (success) 16:47:58 INFO - Running post-run listener: _resource_record_post_run 16:47:58 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 290058240; Read time: 64; Write time: 207292 16:47:58 INFO - TinderboxPrint: CPU usage
13.0% 16:47:58 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 64 16:47:58 INFO - TinderboxPrint: I/O write bytes / time
290,058,240 / 207,292 16:47:58 INFO - TinderboxPrint: CPU idle
8,238.1 (87.0%) 16:47:58 INFO - TinderboxPrint: CPU user
1,183.2 (12.5%) 16:47:58 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:58 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 71323648; Read time: 0; Write time: 95448 16:47:58 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218734592; Read time: 20; Write time: 111844 16:47:59 INFO - Running post-run listener: _upload_blobber_files 16:47:59 INFO - Blob upload gear active. 16:47:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:59 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:59 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:59 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:59 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:47:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.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 - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:04 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/acf9dee6e7bc352cd0155e81196e6ec881cb5b7f6418727b98c948cf7d7c7361b05b2ab43223e0f6100aaccddc1aea9dbac1544b816202d0df5203b92281391f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13491f585c8a3201bdc4f345876b5ec8c2000d10013144344688e4f6a045c0032b92748f01568b0c55e55e68f95c471244c9d96043487d793f8d779a7d2df3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24dbf9d5228a657e34521b18ee824c2a00a74225c6c53dd91501215af48dca34010643a48b1837251ea61a4e9cb150e100f37f0b408080a045cd785506228017"} 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/acf9dee6e7bc352cd0155e81196e6ec881cb5b7f6418727b98c948cf7d7c7361b05b2ab43223e0f6100aaccddc1aea9dbac1544b816202d0df5203b92281391f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13491f585c8a3201bdc4f345876b5ec8c2000d10013144344688e4f6a045c0032b92748f01568b0c55e55e68f95c471244c9d96043487d793f8d779a7d2df3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24dbf9d5228a657e34521b18ee824c2a00a74225c6c53dd91501215af48dca34010643a48b1837251ea61a4e9cb150e100f37f0b408080a045cd785506228017"} 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=1673.985485 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:48:04.652614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:04.656077) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 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/acf9dee6e7bc352cd0155e81196e6ec881cb5b7f6418727b98c948cf7d7c7361b05b2ab43223e0f6100aaccddc1aea9dbac1544b816202d0df5203b92281391f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13491f585c8a3201bdc4f345876b5ec8c2000d10013144344688e4f6a045c0032b92748f01568b0c55e55e68f95c471244c9d96043487d793f8d779a7d2df3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24dbf9d5228a657e34521b18ee824c2a00a74225c6c53dd91501215af48dca34010643a48b1837251ea61a4e9cb150e100f37f0b408080a045cd785506228017"} 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.011181 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/acf9dee6e7bc352cd0155e81196e6ec881cb5b7f6418727b98c948cf7d7c7361b05b2ab43223e0f6100aaccddc1aea9dbac1544b816202d0df5203b92281391f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13491f585c8a3201bdc4f345876b5ec8c2000d10013144344688e4f6a045c0032b92748f01568b0c55e55e68f95c471244c9d96043487d793f8d779a7d2df3b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/24dbf9d5228a657e34521b18ee824c2a00a74225c6c53dd91501215af48dca34010643a48b1837251ea61a4e9cb150e100f37f0b408080a045cd785506228017"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:04.682346) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:04.682647) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1478388009.102137-1805477874 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:48:04.769307) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:04.770016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:48:04.770747) ========= ========= Total master_lag: 0.17 =========