builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1477745205.42 results: success (0) buildid: 20161029030521 builduid: 7386517f5c17460abdaaba0b8ea4a4f2 revision: a2d0463aa39bedba0614826ac72aaab1e579b2c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.416455) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.416919) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.417195) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.432692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.433080) ========= 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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-29 05:46:45-- 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% 17.9M=0.001s 2016-10-29 05:46:46 (17.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.358232 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.805100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.805394) ========= 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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019773 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:45.865496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 05:46:45.865809) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a2d0463aa39bedba0614826ac72aaab1e579b2c5 --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 a2d0463aa39bedba0614826ac72aaab1e579b2c5 --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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-29 05:46:46,347 truncating revision to first 12 chars 2016-10-29 05:46:46,348 Setting DEBUG logging. 2016-10-29 05:46:46,348 attempt 1/10 2016-10-29 05:46:46,348 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a2d0463aa39b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 05:46:47,331 unpacking tar archive at: mozilla-beta-a2d0463aa39b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.321662 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 05:46:47.201388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:47.201821) ========= script_repo_revision: a2d0463aa39bedba0614826ac72aaab1e579b2c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:47.202232) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:47.202523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 05:46:47.214715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-29 05:46:47.215132) ========= /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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:47 INFO - MultiFileLogger online at 20161029 05:46:47 in /builds/slave/test 05:46:47 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 05:46:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:47 INFO - {'append_to_log': False, 05:46:47 INFO - 'base_work_dir': '/builds/slave/test', 05:46:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:46:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:47 INFO - 'branch': 'Mozilla-Beta', 05:46:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:47 INFO - 'config_files': ('talos/linux_config.py',), 05:46:47 INFO - 'default_actions': ('clobber', 05:46:47 INFO - 'read-buildbot-config', 05:46:47 INFO - 'download-and-extract', 05:46:47 INFO - 'populate-webroot', 05:46:47 INFO - 'create-virtualenv', 05:46:47 INFO - 'install', 05:46:47 INFO - 'run-tests'), 05:46:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:47 INFO - 'download_minidump_stackwalk': True, 05:46:47 INFO - 'download_symbols': 'ondemand', 05:46:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:47 INFO - 'installer_path': 'installer.exe', 05:46:47 INFO - 'log_level': 'info', 05:46:47 INFO - 'log_name': 'talos', 05:46:47 INFO - 'log_to_console': True, 05:46:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:47 INFO - 'opt_config_files': (), 05:46:47 INFO - 'pip_index': False, 05:46:47 INFO - 'sps_profile': False, 05:46:47 INFO - 'sps_profile_interval': 0, 05:46:47 INFO - 'suite': 'dromaeojs', 05:46:47 INFO - 'system_bits': '32', 05:46:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:47 INFO - 'title': 'talos-linux64-ix-035', 05:46:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:47 INFO - 'use_talos_json': True, 05:46:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:47 INFO - 'work_dir': 'build'} 05:46:47 INFO - [mozharness: 2016-10-29 12:46:47.782879Z] Running clobber step. 05:46:47 INFO - Running pre-action listener: _resource_record_pre_action 05:46:47 INFO - Running main action method: clobber 05:46:47 INFO - rmtree: /builds/slave/test/build 05:46:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:48 INFO - Running post-action listener: _resource_record_post_action 05:46:48 INFO - [mozharness: 2016-10-29 12:46:48.755853Z] Finished clobber step (success) 05:46:48 INFO - [mozharness: 2016-10-29 12:46:48.755954Z] Running read-buildbot-config step. 05:46:48 INFO - Running pre-action listener: _resource_record_pre_action 05:46:48 INFO - Running main action method: read_buildbot_config 05:46:48 INFO - Using buildbot properties: 05:46:48 INFO - { 05:46:48 INFO - "project": "", 05:46:48 INFO - "product": "firefox", 05:46:48 INFO - "script_repo_revision": "production", 05:46:48 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:46:48 INFO - "repository": "", 05:46:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:46:48 INFO - "buildid": "20161029030521", 05:46:48 INFO - "pgo_build": "True", 05:46:48 INFO - "basedir": "/builds/slave/test", 05:46:48 INFO - "buildnumber": 454, 05:46:48 INFO - "slavename": "talos-linux64-ix-035", 05:46:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:46:48 INFO - "platform": "ubuntu64_hw", 05:46:48 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:46:48 INFO - "branch": "mozilla-beta", 05:46:48 INFO - "repo_path": "releases/mozilla-beta", 05:46:48 INFO - "revision": "a2d0463aa39bedba0614826ac72aaab1e579b2c5", 05:46:48 INFO - "stage_platform": "linux64-pgo", 05:46:48 INFO - "builduid": "7386517f5c17460abdaaba0b8ea4a4f2", 05:46:48 INFO - "slavebuilddir": "test" 05:46:48 INFO - } 05:46:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 05:46:48 INFO - Running post-action listener: _resource_record_post_action 05:46:48 INFO - [mozharness: 2016-10-29 12:46:48.758389Z] Finished read-buildbot-config step (success) 05:46:48 INFO - [mozharness: 2016-10-29 12:46:48.758476Z] Running download-and-extract step. 05:46:48 INFO - Running pre-action listener: _resource_record_pre_action 05:46:48 INFO - Running main action method: download_and_extract 05:46:48 INFO - mkdir: /builds/slave/test/build/tests 05:46:48 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')]} 05:46:48 INFO - https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:46:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:46:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:46:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:46:48 INFO - retry: Failed, sleeping 30 seconds before retrying 05:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:47:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:47:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:47:18 INFO - retry: Failed, sleeping 60 seconds before retrying 05:48:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:48:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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! 05:48:18 INFO - Caught exception: HTTP Error 404: Not Found 05:48:18 INFO - Caught exception: HTTP Error 404: Not Found 05:48:18 INFO - Caught exception: HTTP Error 404: Not Found 05:48:18 INFO - trying https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:18 INFO - Downloading https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:19 INFO - Downloaded 1327 bytes. 05:48:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:19 INFO - Using the following test package requirements: 05:48:19 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:48:19 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:48:19 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 05:48:19 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'jsshell-linux-x86_64.zip'], 05:48:19 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:48:19 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:48:19 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 05:48:19 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 05:48:19 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:48:19 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:19 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:48:19 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:48:19 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')]} 05:48:19 INFO - https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:48:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:48:19 INFO - retry: Failed, sleeping 30 seconds before retrying 05:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:48:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:48:49 INFO - retry: Failed, sleeping 60 seconds before retrying 05:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:49:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:49:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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! 05:49:49 INFO - Caught exception: HTTP Error 404: Not Found 05:49:49 INFO - Caught exception: HTTP Error 404: Not Found 05:49:49 INFO - Caught exception: HTTP Error 404: Not Found 05:49:49 INFO - trying https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - Downloading https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:49:50 INFO - Downloaded 21789209 bytes. 05:49:50 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'] 05:49:50 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 05:49:51 INFO - Return code: 0 05:49:51 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 05:49:51 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')]} 05:49:51 INFO - https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:49:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:49:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:49:51 INFO - retry: Failed, sleeping 30 seconds before retrying 05:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:50:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:50:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:50:21 INFO - retry: Failed, sleeping 60 seconds before retrying 05:51:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:51:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:51:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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! 05:51:21 INFO - Caught exception: HTTP Error 404: Not Found 05:51:21 INFO - Caught exception: HTTP Error 404: Not Found 05:51:21 INFO - Caught exception: HTTP Error 404: Not Found 05:51:21 INFO - trying https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:51:21 INFO - Downloading https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:22 INFO - Downloaded 21789209 bytes. 05:51:22 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'] 05:51:22 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 05:51:24 INFO - Return code: 0 05:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:51:24 INFO - https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:51:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:51:24 INFO - retry: Failed, sleeping 30 seconds before retrying 05:51:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:51:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:51:54 INFO - retry: Failed, sleeping 60 seconds before retrying 05:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:52:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:52:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:52:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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! 05:52:54 INFO - Caught exception: HTTP Error 404: Not Found 05:52:54 INFO - Caught exception: HTTP Error 404: Not Found 05:52:54 INFO - Caught exception: HTTP Error 404: Not Found 05:52:54 INFO - trying https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:52:55 INFO - Downloaded 14413762 bytes. 05:52:55 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'] 05:52:55 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 05:52:55 INFO - Return code: 0 05:52: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')]} 05:52:55 INFO - https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:52:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:52:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:52:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:52:55 INFO - retry: Failed, sleeping 30 seconds before retrying 05:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:53:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:53:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:53:25 INFO - retry: Failed, sleeping 60 seconds before retrying 05:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:54:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:54:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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! 05:54:26 INFO - Caught exception: HTTP Error 404: Not Found 05:54:26 INFO - Caught exception: HTTP Error 404: Not Found 05:54:26 INFO - Caught exception: HTTP Error 404: Not Found 05:54:26 INFO - trying https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:26 INFO - Downloading https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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 05:54:27 INFO - Downloaded 56800539 bytes. 05:54:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:27 INFO - mkdir: /builds/slave/test/properties 05:54:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:27 INFO - Contents: 05:54:27 INFO - build_url:https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:28 INFO - Running post-action listener: _resource_record_post_action 05:54:28 INFO - Running post-action listener: set_extra_try_arguments 05:54:28 INFO - [mozharness: 2016-10-29 12:54:28.765454Z] Finished download-and-extract step (success) 05:54:28 INFO - [mozharness: 2016-10-29 12:54:28.765690Z] Running populate-webroot step. 05:54:28 INFO - Running pre-action listener: _resource_record_pre_action 05:54:28 INFO - Running main action method: populate_webroot 05:54:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:28 INFO - '--tppagecycles', 05:54:28 INFO - '7'], 05:54:28 INFO - 'tests': ['tsvgm']}}, 05:54:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['damp', 'tps']}, 05:54:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'tests': ['damp', 'tps']}, 05:54:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['dromaeo_dom']}, 05:54:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['basic_compositor_video']}, 05:54:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:54:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['a11yr', 05:54:28 INFO - 'ts_paint', 05:54:28 INFO - 'tpaint', 05:54:28 INFO - 'sessionrestore', 05:54:28 INFO - 'sessionrestore_no_auto_restore', 05:54:28 INFO - 'tabpaint']}, 05:54:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['tsvgx', 05:54:28 INFO - 'tsvgr_opacity', 05:54:28 INFO - 'tart', 05:54:28 INFO - 'tscrollx', 05:54:28 INFO - 'cart']}, 05:54:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:28 INFO - 'tsvgr_opacity', 05:54:28 INFO - 'tart', 05:54:28 INFO - 'tscrollx', 05:54:28 INFO - 'cart']}, 05:54:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'talos_options': ['--disable-e10s'], 05:54:28 INFO - 'tests': ['tp5o']}, 05:54:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'tests': ['tp5o']}, 05:54:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'talos_options': ['--disable-e10s', 05:54:28 INFO - '--xperf_path', 05:54:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:28 INFO - 'tests': ['tp5n']}, 05:54:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:28 INFO - 'talos_options': ['--xperf_path', 05:54:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:28 INFO - 'tests': ['tp5n']}}, 05:54:28 INFO - 'talos.zip': {'path': '', 05:54:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:28 INFO - Running post-action listener: _resource_record_post_action 05:54:28 INFO - [mozharness: 2016-10-29 12:54:28.781475Z] Finished populate-webroot step (success) 05:54:28 INFO - [mozharness: 2016-10-29 12:54:28.781564Z] Running create-virtualenv step. 05:54:28 INFO - Running pre-action listener: _resource_record_pre_action 05:54:28 INFO - Running main action method: create_virtualenv 05:54:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:28 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 05:54:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:28 INFO - Using real prefix '/usr' 05:54:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:54:31 INFO - Installing pip.................done. 05:54:31 INFO - Return code: 0 05:54:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:54:31 INFO - Using env: {'DISPLAY': ':0', 05:54:31 INFO - 'HOME': '/home/cltbld', 05:54:31 INFO - 'LANG': 'en_US.UTF-8', 05:54:31 INFO - 'LANGUAGE': 'en_US:en', 05:54:31 INFO - 'LOGNAME': 'cltbld', 05:54:31 INFO - 'MAIL': '/var/mail/cltbld', 05:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:31 INFO - 'MOZ_NO_REMOTE': '1', 05:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:31 INFO - 'NO_EM_RESTART': '1', 05:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:31 INFO - 'PWD': '/builds/slave/test', 05:54:31 INFO - 'SHELL': '/bin/bash', 05:54:31 INFO - 'SHLVL': '1', 05:54:31 INFO - 'TERM': 'linux', 05:54:31 INFO - 'TMOUT': '86400', 05:54:31 INFO - 'USER': 'cltbld', 05:54:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:31 INFO - '_': '/tools/buildbot/bin/python'} 05:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:31 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 05:54:31 INFO - Cleaning up... 05:54:31 INFO - Return code: 0 05:54:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:54:31 INFO - Using env: {'DISPLAY': ':0', 05:54:31 INFO - 'HOME': '/home/cltbld', 05:54:31 INFO - 'LANG': 'en_US.UTF-8', 05:54:31 INFO - 'LANGUAGE': 'en_US:en', 05:54:31 INFO - 'LOGNAME': 'cltbld', 05:54:31 INFO - 'MAIL': '/var/mail/cltbld', 05:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:31 INFO - 'MOZ_NO_REMOTE': '1', 05:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:31 INFO - 'NO_EM_RESTART': '1', 05:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:31 INFO - 'PWD': '/builds/slave/test', 05:54:31 INFO - 'SHELL': '/bin/bash', 05:54:31 INFO - 'SHLVL': '1', 05:54:31 INFO - 'TERM': 'linux', 05:54:31 INFO - 'TMOUT': '86400', 05:54:31 INFO - 'USER': 'cltbld', 05:54:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:31 INFO - '_': '/tools/buildbot/bin/python'} 05:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:31 INFO - Downloading/unpacking psutil>=3.1.1 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:54:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:33 INFO - Installing collected packages: psutil 05:54:33 INFO - Running setup.py install for psutil 05:54:33 INFO - building 'psutil._psutil_linux' extension 05:54:33 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 05:54:34 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 05:54:34 INFO - building 'psutil._psutil_posix' extension 05:54:34 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 05:54:34 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 05:54:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:34 INFO - Successfully installed psutil 05:54:34 INFO - Cleaning up... 05:54:34 INFO - Return code: 0 05:54:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:54:34 INFO - Using env: {'DISPLAY': ':0', 05:54:34 INFO - 'HOME': '/home/cltbld', 05:54:34 INFO - 'LANG': 'en_US.UTF-8', 05:54:34 INFO - 'LANGUAGE': 'en_US:en', 05:54:34 INFO - 'LOGNAME': 'cltbld', 05:54:34 INFO - 'MAIL': '/var/mail/cltbld', 05:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:34 INFO - 'MOZ_NO_REMOTE': '1', 05:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:34 INFO - 'NO_EM_RESTART': '1', 05:54:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:34 INFO - 'PWD': '/builds/slave/test', 05:54:34 INFO - 'SHELL': '/bin/bash', 05:54:34 INFO - 'SHLVL': '1', 05:54:34 INFO - 'TERM': 'linux', 05:54:34 INFO - 'TMOUT': '86400', 05:54:34 INFO - 'USER': 'cltbld', 05:54:34 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:34 INFO - '_': '/tools/buildbot/bin/python'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:54:36 INFO - Installing collected packages: mozsystemmonitor 05:54:36 INFO - Running setup.py install for mozsystemmonitor 05:54:36 INFO - Successfully installed mozsystemmonitor 05:54:36 INFO - Cleaning up... 05:54:36 INFO - Return code: 0 05:54:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:54:36 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')]} 05:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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')]} 05:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:36 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 05:54:36 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 05:54:36 INFO - Using env: {'DISPLAY': ':0', 05:54:36 INFO - 'HOME': '/home/cltbld', 05:54:36 INFO - 'LANG': 'en_US.UTF-8', 05:54:36 INFO - 'LANGUAGE': 'en_US:en', 05:54:36 INFO - 'LOGNAME': 'cltbld', 05:54:36 INFO - 'MAIL': '/var/mail/cltbld', 05:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:36 INFO - 'MOZ_NO_REMOTE': '1', 05:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:36 INFO - 'NO_EM_RESTART': '1', 05:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:36 INFO - 'PWD': '/builds/slave/test', 05:54:36 INFO - 'SHELL': '/bin/bash', 05:54:36 INFO - 'SHLVL': '1', 05:54:36 INFO - 'TERM': 'linux', 05:54:36 INFO - 'TMOUT': '86400', 05:54:36 INFO - 'USER': 'cltbld', 05:54:36 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:36 INFO - '_': '/tools/buildbot/bin/python'} 05:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:39 INFO - warning: no files found matching '*.txt' 05:54:39 INFO - no previously-included directories found matching 'build' 05:54:39 INFO - no previously-included directories found matching 'dist' 05:54:39 INFO - no previously-included directories found matching '.git*' 05:54:39 INFO - Installing collected packages: jsonschema, functools32 05:54:39 INFO - Running setup.py install for functools32 05:54:39 INFO - warning: no files found matching '*.txt' 05:54:39 INFO - no previously-included directories found matching 'build' 05:54:39 INFO - no previously-included directories found matching 'dist' 05:54:39 INFO - no previously-included directories found matching '.git*' 05:54:39 INFO - Successfully installed jsonschema functools32 05:54:39 INFO - Cleaning up... 05:54:39 INFO - Return code: 0 05:54:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:54:39 INFO - Using env: {'DISPLAY': ':0', 05:54:39 INFO - 'HOME': '/home/cltbld', 05:54:39 INFO - 'LANG': 'en_US.UTF-8', 05:54:39 INFO - 'LANGUAGE': 'en_US:en', 05:54:39 INFO - 'LOGNAME': 'cltbld', 05:54:39 INFO - 'MAIL': '/var/mail/cltbld', 05:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:39 INFO - 'MOZ_NO_REMOTE': '1', 05:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:39 INFO - 'NO_EM_RESTART': '1', 05:54:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:39 INFO - 'PWD': '/builds/slave/test', 05:54:39 INFO - 'SHELL': '/bin/bash', 05:54:39 INFO - 'SHLVL': '1', 05:54:39 INFO - 'TERM': 'linux', 05:54:39 INFO - 'TMOUT': '86400', 05:54:39 INFO - 'USER': 'cltbld', 05:54:39 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:39 INFO - '_': '/tools/buildbot/bin/python'} 05:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:54:39 INFO - Cleaning up... 05:54:39 INFO - Return code: 0 05:54:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:54:39 INFO - Using env: {'DISPLAY': ':0', 05:54:39 INFO - 'HOME': '/home/cltbld', 05:54:39 INFO - 'LANG': 'en_US.UTF-8', 05:54:39 INFO - 'LANGUAGE': 'en_US:en', 05:54:39 INFO - 'LOGNAME': 'cltbld', 05:54:39 INFO - 'MAIL': '/var/mail/cltbld', 05:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:39 INFO - 'MOZ_NO_REMOTE': '1', 05:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:39 INFO - 'NO_EM_RESTART': '1', 05:54:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:39 INFO - 'PWD': '/builds/slave/test', 05:54:39 INFO - 'SHELL': '/bin/bash', 05:54:39 INFO - 'SHLVL': '1', 05:54:39 INFO - 'TERM': 'linux', 05:54:39 INFO - 'TMOUT': '86400', 05:54:39 INFO - 'USER': 'cltbld', 05:54:39 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:39 INFO - '_': '/tools/buildbot/bin/python'} 05:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:54:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:54:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:42 INFO - Downloading docopt-0.6.1.tar.gz 05:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:54:42 INFO - Installing collected packages: blobuploader, requests, docopt 05:54:42 INFO - Running setup.py install for blobuploader 05:54:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:54:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:54:42 INFO - Running setup.py install for requests 05:54:43 INFO - Running setup.py install for docopt 05:54:43 INFO - Successfully installed blobuploader requests docopt 05:54:43 INFO - Cleaning up... 05:54:43 INFO - Return code: 0 05:54:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:43 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')]} 05:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 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')]} 05:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:43 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 05:54:43 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 05:54:43 INFO - Using env: {'DISPLAY': ':0', 05:54:43 INFO - 'HOME': '/home/cltbld', 05:54:43 INFO - 'LANG': 'en_US.UTF-8', 05:54:43 INFO - 'LANGUAGE': 'en_US:en', 05:54:43 INFO - 'LOGNAME': 'cltbld', 05:54:43 INFO - 'MAIL': '/var/mail/cltbld', 05:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:43 INFO - 'MOZ_NO_REMOTE': '1', 05:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:43 INFO - 'NO_EM_RESTART': '1', 05:54:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:43 INFO - 'PWD': '/builds/slave/test', 05:54:43 INFO - 'SHELL': '/bin/bash', 05:54:43 INFO - 'SHLVL': '1', 05:54:43 INFO - 'TERM': 'linux', 05:54:43 INFO - 'TMOUT': '86400', 05:54:43 INFO - 'USER': 'cltbld', 05:54:43 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:43 INFO - '_': '/tools/buildbot/bin/python'} 05:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 INFO - Running setup.py (path:/tmp/pip-9o9I2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 INFO - Running setup.py (path:/tmp/pip-yqodFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:43 INFO - Running setup.py (path:/tmp/pip-vLuNK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:43 INFO - Running setup.py (path:/tmp/pip-UJWDDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:43 INFO - Running setup.py (path:/tmp/pip-Gh5_E2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:43 INFO - Running setup.py (path:/tmp/pip-kTDXbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:43 INFO - Running setup.py (path:/tmp/pip-k7Jw40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:43 INFO - Running setup.py (path:/tmp/pip-dow9Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:44 INFO - Running setup.py (path:/tmp/pip-T9kjnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:44 INFO - Running setup.py (path:/tmp/pip-BqikVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 INFO - Running setup.py (path:/tmp/pip-qw4jEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:44 INFO - Running setup.py (path:/tmp/pip-O6_j5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:44 INFO - Running setup.py (path:/tmp/pip-MOm8GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:44 INFO - Running setup.py (path:/tmp/pip-884ocY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:44 INFO - Running setup.py (path:/tmp/pip-67NRXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:44 INFO - Running setup.py (path:/tmp/pip-sPZsMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:44 INFO - Running setup.py (path:/tmp/pip-V1obYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:54:44 INFO - Running setup.py install for manifestparser 05:54:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Running setup.py install for mozcrash 05:54:44 INFO - Running setup.py install for mozdebug 05:54:44 INFO - Running setup.py install for mozdevice 05:54:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozfile 05:54:45 INFO - Running setup.py install for mozhttpd 05:54:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozinfo 05:54:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozInstall 05:54:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozleak 05:54:45 INFO - Running setup.py install for mozlog 05:54:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for moznetwork 05:54:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozprocess 05:54:45 INFO - Running setup.py install for mozprofile 05:54:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozrunner 05:54:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:46 INFO - Running setup.py install for mozscreenshot 05:54:46 INFO - Running setup.py install for moztest 05:54:46 INFO - Running setup.py install for mozversion 05:54:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:46 INFO - Cleaning up... 05:54:46 INFO - Return code: 0 05:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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')]} 05:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:46 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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:46 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 05:54:46 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 05:54:46 INFO - Using env: {'DISPLAY': ':0', 05:54:46 INFO - 'HOME': '/home/cltbld', 05:54:46 INFO - 'LANG': 'en_US.UTF-8', 05:54:46 INFO - 'LANGUAGE': 'en_US:en', 05:54:46 INFO - 'LOGNAME': 'cltbld', 05:54:46 INFO - 'MAIL': '/var/mail/cltbld', 05:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:46 INFO - 'MOZ_NO_REMOTE': '1', 05:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:46 INFO - 'NO_EM_RESTART': '1', 05:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:46 INFO - 'PWD': '/builds/slave/test', 05:54:46 INFO - 'SHELL': '/bin/bash', 05:54:46 INFO - 'SHLVL': '1', 05:54:46 INFO - 'TERM': 'linux', 05:54:46 INFO - 'TMOUT': '86400', 05:54:46 INFO - 'USER': 'cltbld', 05:54:46 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:46 INFO - '_': '/tools/buildbot/bin/python'} 05:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 INFO - Running setup.py (path:/tmp/pip-ed9coK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 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)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 INFO - Running setup.py (path:/tmp/pip-FyFXND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 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)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:46 INFO - Running setup.py (path:/tmp/pip-06IOIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:46 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)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:46 INFO - Running setup.py (path:/tmp/pip-u2bjvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:46 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)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:46 INFO - Running setup.py (path:/tmp/pip-pptC4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:47 INFO - Running setup.py (path:/tmp/pip-aNWHpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:47 INFO - Running setup.py (path:/tmp/pip-QC_X9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:47 INFO - Running setup.py (path:/tmp/pip-h5JMXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:47 INFO - Running setup.py (path:/tmp/pip-Dd3V3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:47 INFO - Running setup.py (path:/tmp/pip-dEP3x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 INFO - Running setup.py (path:/tmp/pip-kXv999-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:47 INFO - Running setup.py (path:/tmp/pip-D_o1FK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:47 INFO - Running setup.py (path:/tmp/pip-A_1ufT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:47 INFO - Running setup.py (path:/tmp/pip-UC_dEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:47 INFO - Running setup.py (path:/tmp/pip-Mukh2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:47 INFO - Running setup.py (path:/tmp/pip-5DhAYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:47 INFO - Running setup.py (path:/tmp/pip-J1HF_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:47 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 05:54:47 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 05:54:47 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 05:54:47 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 05:54:47 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 05:54:47 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 05:54:49 INFO - Downloading blessings-1.6.tar.gz 05:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:49 INFO - Installing collected packages: blessings 05:54:49 INFO - Running setup.py install for blessings 05:54:49 INFO - Successfully installed blessings 05:54:49 INFO - Cleaning up... 05:54:50 INFO - Return code: 0 05:54:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:50 INFO - Reading from file tmpfile_stdout 05:54:50 INFO - Current package versions: 05:54:50 INFO - argparse == 1.2.1 05:54:50 INFO - blessings == 1.6 05:54:50 INFO - blobuploader == 1.2.4 05:54:50 INFO - docopt == 0.6.1 05:54:50 INFO - functools32 == 3.2.3-2 05:54:50 INFO - jsonschema == 2.5.1 05:54:50 INFO - manifestparser == 1.1 05:54:50 INFO - mozInstall == 1.12 05:54:50 INFO - mozcrash == 0.17 05:54:50 INFO - mozdebug == 0.1 05:54:50 INFO - mozdevice == 0.48 05:54:50 INFO - mozfile == 1.2 05:54:50 INFO - mozhttpd == 0.7 05:54:50 INFO - mozinfo == 0.9 05:54:50 INFO - mozleak == 0.1 05:54:50 INFO - mozlog == 3.3 05:54:50 INFO - moznetwork == 0.27 05:54:50 INFO - mozprocess == 0.23 05:54:50 INFO - mozprofile == 0.28 05:54:50 INFO - mozrunner == 6.12 05:54:50 INFO - mozscreenshot == 0.1 05:54:50 INFO - mozsystemmonitor == 0.3 05:54:50 INFO - moztest == 0.7 05:54:50 INFO - mozversion == 1.4 05:54:50 INFO - psutil == 3.1.1 05:54:50 INFO - requests == 1.2.3 05:54:50 INFO - wsgiref == 0.1.2 05:54:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 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 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:50 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 05:54:50 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 05:54:50 INFO - Using env: {'DISPLAY': ':0', 05:54:50 INFO - 'HOME': '/home/cltbld', 05:54:50 INFO - 'LANG': 'en_US.UTF-8', 05:54:50 INFO - 'LANGUAGE': 'en_US:en', 05:54:50 INFO - 'LOGNAME': 'cltbld', 05:54:50 INFO - 'MAIL': '/var/mail/cltbld', 05:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:50 INFO - 'MOZ_NO_REMOTE': '1', 05:54:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:50 INFO - 'NO_EM_RESTART': '1', 05:54:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:50 INFO - 'PWD': '/builds/slave/test', 05:54:50 INFO - 'SHELL': '/bin/bash', 05:54:50 INFO - 'SHLVL': '1', 05:54:50 INFO - 'TERM': 'linux', 05:54:50 INFO - 'TMOUT': '86400', 05:54:50 INFO - 'USER': 'cltbld', 05:54:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:50 INFO - '_': '/tools/buildbot/bin/python'} 05:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 INFO - Cleaning up... 05:54:50 INFO - Return code: 0 05:54:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1081180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', '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 05:54:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:50 INFO - Using env: {'DISPLAY': ':0', 05:54:50 INFO - 'HOME': '/home/cltbld', 05:54:50 INFO - 'LANG': 'en_US.UTF-8', 05:54:50 INFO - 'LANGUAGE': 'en_US:en', 05:54:50 INFO - 'LOGNAME': 'cltbld', 05:54:50 INFO - 'MAIL': '/var/mail/cltbld', 05:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:50 INFO - 'MOZ_NO_REMOTE': '1', 05:54:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:50 INFO - 'NO_EM_RESTART': '1', 05:54:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:50 INFO - 'PWD': '/builds/slave/test', 05:54:50 INFO - 'SHELL': '/bin/bash', 05:54:50 INFO - 'SHLVL': '1', 05:54:50 INFO - 'TERM': 'linux', 05:54:50 INFO - 'TMOUT': '86400', 05:54:50 INFO - 'USER': 'cltbld', 05:54:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:50 INFO - '_': '/tools/buildbot/bin/python'} 05:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:54:50 INFO - Cleaning up... 05:54:50 INFO - Return code: 0 05:54:50 INFO - Running post-action listener: _resource_record_post_action 05:54:50 INFO - Running post-action listener: _start_resource_monitoring 05:54:50 INFO - Starting resource monitoring. 05:54:50 INFO - [mozharness: 2016-10-29 12:54:50.733429Z] Finished create-virtualenv step (success) 05:54:50 INFO - [mozharness: 2016-10-29 12:54:50.733914Z] Running install step. 05:54:50 INFO - Running pre-action listener: _resource_record_pre_action 05:54:50 INFO - Running main action method: install 05:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:50 INFO - Reading from file tmpfile_stdout 05:54:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:51 INFO - Reading from file tmpfile_stdout 05:54:51 INFO - Output received: 05:54:51 INFO - Usage: mozinstall [options] installer 05:54:51 INFO - Options: 05:54:51 INFO - -h, --help show this help message and exit 05:54:51 INFO - -d DEST, --destination=DEST 05:54:51 INFO - Directory to install application into. [default: 05:54:51 INFO - "/builds/slave/test"] 05:54:51 INFO - --app=APP Application being installed. [default: firefox] 05:54:51 INFO - mkdir: /builds/slave/test/build/application 05:54:51 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'] 05:54:51 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 05:55:05 INFO - Reading from file tmpfile_stdout 05:55:05 INFO - Output received: 05:55:05 INFO - /builds/slave/test/build/application/firefox/firefox 05:55:05 INFO - Running post-action listener: _resource_record_post_action 05:55:05 INFO - [mozharness: 2016-10-29 12:55:05.027303Z] Finished install step (success) 05:55:05 INFO - [mozharness: 2016-10-29 12:55:05.027578Z] Running run-tests step. 05:55:05 INFO - Running pre-action listener: _resource_record_pre_action 05:55:05 INFO - Running main action method: run_tests 05:55:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:05 INFO - Python 2.7.3 05:55:05 INFO - Return code: 0 05:55:05 INFO - grabbing minidump binary from tooltool 05:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:05 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 0x107f3b0>, '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 0x10802b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:05 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 05:55:05 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 05:55:05 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 05:55:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:05 INFO - Return code: 0 05:55:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:55:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:55:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:05 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/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 05:55:05 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/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 05:55:05 INFO - Using env: {'DISPLAY': ':0', 05:55:05 INFO - 'HOME': '/home/cltbld', 05:55:05 INFO - 'LANG': 'en_US.UTF-8', 05:55:05 INFO - 'LANGUAGE': 'en_US:en', 05:55:05 INFO - 'LOGNAME': 'cltbld', 05:55:05 INFO - 'MAIL': '/var/mail/cltbld', 05:55:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:05 INFO - 'MOZ_NO_REMOTE': '1', 05:55:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:05 INFO - 'NO_EM_RESTART': '1', 05:55:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:05 INFO - 'PWD': '/builds/slave/test', 05:55:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:05 INFO - 'SHELL': '/bin/bash', 05:55:05 INFO - 'SHLVL': '1', 05:55:05 INFO - 'TERM': 'linux', 05:55:05 INFO - 'TMOUT': '86400', 05:55:05 INFO - 'USER': 'cltbld', 05:55:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928', 05:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:05 INFO - '_': '/tools/buildbot/bin/python'} 05:55:05 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/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 05:55:05 INFO - mozversion application_buildid: 20161029030521 05:55:05 INFO - mozversion application_changeset: a2d0463aa39bedba0614826ac72aaab1e579b2c5 05:55:05 INFO - mozversion application_display_name: Firefox 05:55:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:05 INFO - mozversion application_name: Firefox 05:55:05 INFO - mozversion application_remotingname: firefox 05:55:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:55:05 INFO - mozversion application_vendor: Mozilla 05:55:05 INFO - mozversion application_version: 50.0 05:55:05 INFO - mozversion platform_buildid: 20161029030521 05:55:05 INFO - mozversion platform_changeset: a2d0463aa39bedba0614826ac72aaab1e579b2c5 05:55:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:55:05 INFO - mozversion platform_version: 50.0 05:55:05 INFO - using testdate: 1477745705 05:55:05 INFO - actual date: 1477745705 05:55:05 INFO - starting webserver on 'localhost:38278' 05:55:05 INFO - SUITE-START | Running 2 tests 05:55:05 INFO - TEST-START | dromaeo_css 05:55:05 INFO - operating with platform_type : linux_ 05:55:05 INFO - Initialising browser for dromaeo_css test... 05:55:05 INFO - TEST-INFO | started process 30498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBD95Cp/profile http://localhost:38278/getInfo.html) 05:55:09 INFO - PROCESS | 30498 | __metrics Screen width/height:1600/1200 05:55:09 INFO - PROCESS | 30498 | colorDepth:24 05:55:09 INFO - PROCESS | 30498 | Browser inner width/height: 1024/697 05:55:09 INFO - PROCESS | 30498 | __metrics 05:55:09 INFO - PROCESS | 30498 | 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" 05:55:09 INFO - PROCESS | 30498 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:55:10 INFO - TEST-INFO | 30498: exit 0 05:55:10 INFO - Browser initialized. 05:55:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:10 INFO - TEST-INFO | started process 30586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBD95Cp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:22 INFO - PROCESS | 30586 | RSS: Main: 144265216 05:55:22 INFO - PROCESS | 30586 | 05:56:14 INFO - PROCESS | 30586 | 1477745774186 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] 05:58:21 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38278/tests/dromaeo/cssquery-ext.html) 05:58:22 INFO - PROCESS | 30586 | RSS: Main: 264847360 05:58:22 INFO - PROCESS | 30586 | 06:01:25 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-ext.html (next: http://localhost:38278/tests/dromaeo/cssquery-jquery.html) 06:01:25 INFO - PROCESS | 30586 | RSS: Main: 230735872 06:01:25 INFO - PROCESS | 30586 | 06:04:27 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38278/tests/dromaeo/cssquery-mootools.html) 06:04:28 INFO - PROCESS | 30586 | RSS: Main: 178327552 06:04:28 INFO - PROCESS | 30586 | 06:07:30 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38278/tests/dromaeo/cssquery-prototype.html) 06:07:30 INFO - PROCESS | 30586 | RSS: Main: 176963584 06:07:30 INFO - PROCESS | 30586 | 06:10:33 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38278/tests/dromaeo/cssquery-yui.html) 06:10:33 INFO - PROCESS | 30586 | RSS: Main: 176566272 06:10:33 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | Cycle 1(1): loaded http://localhost:38278/tests/dromaeo/cssquery-yui.html (next: http://localhost:38278/tests/dromaeo/cssquery-dojo.html) 06:13:36 INFO - PROCESS | 30586 | RSS: Main: 249954304 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | __start_tp_report 06:13:36 INFO - PROCESS | 30586 | _x_x_mozilla_page_load 06:13:36 INFO - PROCESS | 30586 | _x_x_mozilla_page_load_details 06:13:36 INFO - PROCESS | 30586 | |i|pagename|runs| 06:13:36 INFO - PROCESS | 30586 | |0;dojo.html;3141.403133404653;3160.431122397969;3199.3601279744043;3219.114743445554;3248.821314827685;5500.312460942382;5534.612577119603;5586.078297080987;5587.133994230896;5591.30108736408;5602.1596760485945;5662.178984047321;5670.518005969498;5683.403242659523;5696.202531645573;5099.872503187413;5131.255967884655;5176.896462070757;5190.896182076356;5195.298634684326;4901.950980490203;5189.792408303663;5210.5310522053005;5219.321488206528;5225.346831646044;4887.2913427552985;4969.428515720702;5040.521150490697;5080.364954380702;5087.9745601272125;5035.899282014376;5051.06555287273;5080.441151473335;5152.793888244466;5188.662736922088;4991.575716064139;5047.64666473347;5106.412762532319;5106.719130447824;5117.692938423706;5336.946630533684;5384.865378365533;5429.4299098594865;5453.209284653703;5468.863278418032;5885.382034886361;5899.439553242435;5924.081767326072;5978.0435130379365;5979.611325263844;5668.858278543069;6015.488683461911;6019.939800602026;6034.456898879122;6036.849078773022;5465.726713664301;5478.835634930959;5542.806001789958;5564.8052318169475;5586.553075753889;6078.574499784972;6082.36135205797;6084.026555751058;6085.513158947274;6091.725872335667;5532.17017447378;5724.141378793214;5868.119782032729;5868.647881127146;5870.295564532284;5872.148538461899;5929.73316200772;5946.316173640001;5982.46157845796;5983.9401605983385;5907.202527658711;5911.231539899872;5913.526917846562;5916.704164791743;5917.6745279010065;5641.774329026875;5775.826725198251;5903.144044113579;5910.527157827363;5916.7337469813965;4539.228331183706;4552.726836389761;4561.566651168134;4566.520515345841;4587.7017993830295;4043.4137050127565;4045.4943132108488;4048.554658987509;4053.6554392876346;4056.2698714231133;5477.452254774491;5602;5664.8867022659315;5675.148727690879;5680.602357834912;5925.466707996301;6017.759289628453;6038.30559485656;6060.757569697251;6075.696215189223;5727.713614319267;5730.025895597759;5735.11105778605;5739.62692424991;5745.109508026263;4990.0269447457395;5192.350956130484;5193.324867767166;5199.766010529535;5204.609654275944;6096.055111457731;6116.396640261158;6128.533679014861;6220.595661282002;6233.625982441159;6032.366601506778;6034.879302414015;6042.486388657014;6045.1234570987845;6085.939140608537;5990.101484777144;6161.260648722182;6235.844103897258;6249.843753906189;6249.8750024998335;5738.713064346849;5756.453136951983;5801.477866991822;5837.474627283565;5865.178874957594;5917.763289468545;5918.674472904032;5923.526117910471;5924.407559244213;5928.881422371443;5571.637843540157;5797.652140871562;5802.506786923159;5804.854878627899;5806.361300256884;5722;5733.0540460823495;5747.885042299215;5811.651300921958;5848.590598658053;5753.568482363923;5770.451807078321;5772.018756811436;5785.363610002981;5794.362620111869;5630.5777066720975;5760.452756988079;5766.855828604318;5805.129230615442;5811.27359080115;5635.774569017193;5713.085906254979;5714.342850572238;5757.222774925499;5773.22061521706 06:13:36 INFO - PROCESS | 30586 | |1;ext.html;585.9765609375626;643.5559463969861;658.7694306992552;665.0689035350508;676.952613317068;34119.82940085299;44755.10489790206;45629;45646;45704.77147614262;46691.53308466914;47430.52569474304;47443.76278118613;47460.76269618647;47466.52533474664;8698.826023479527;9317.347785655007;9474.194693451065;9477.43135411874;11253.66239012831;11013.229073964825;11398.145139114558;11407.08743300534;11416.486258118362;11417.48621312043;11522.423878806068;11576.247543909662;11600.651980440558;11605.187636865423;11605.593804216855;11445;11483.770324593545;11495.942520287428;11500.079993600491;11500.367479788609;11303.943480282629;11338.376389298584;11384.943075284653;11389.943050284779;11390.658280251606;44106.338404923634;44356.778216108716;44395.11209775786;44509.554904451186;44517.109657806664;3694.4458331250257;4131.173765246933;4319.200947824663;4360.781960901974;4506.819727210907;34282.31435371278;34335.31329373398;34472.31055378904;35089.82455087734;36591.63408365909;4606.147862645421;4739;4748.786304616266;4781.976090119562;4800.879978000543;250188;250226;250359;251477.7426112876;252854;57775;58240.70879645574;58280.708596456745;58294;58396.70801645965;38813.805930970164;39125.80437097796;39428.2114357717;40734.38898416526;40754.79622601868;16414.09722465252;16503.83496165047;16517.834821651628;16535.173241338125;16543.751843722355;12434.75130497385;12756.234625922474;12776.169548979287;12985;12988.220706757436;19620.705689414386;19834.305799296955;20008.39974800758;20769.27307544229;20878.060487278108;7093.900445430966;7247.710091596383;7341.192468828424;7347.8530429392185;7456.545973535217;1046.3930920066343;1081.670090622352;1114.2757207814984;1145.8281257811395;1157.3113997171838;32229.194270143435;33274;34474.482882756776;34849.12877178091;35117.47323790145;1134.9035331996708;1155.9017483513828;1166.0904494494307;1182.8876256755595;1217.2209785737136;17004.914975425043;18108.728369074473;18296.817031829778;18303.267869285344;18439.26242950267;569050;575672;576803;577646;579137;59593.70203148956;59665.70167149136;60026.39973600208;60106;60115.39884601098;1284.8137020132028;1329.6077657091182;1417.7164567086625;1430.1061836352071;1450.3400952566558;33816;35775.82112089423;35848;35949.64050359463;36194.63805361913;52284.21573676322;52874.73562632162;52932.470675294295;53108.46891531035;53134.73432632812;411549;411621;412081;412641;414082;27435;29207.561886571293;30124;30177.54733678953;31104;50030.49969500258;50162;50238.74880625573;50473.49526504688;51124.74437627788;43491.782541087094;43532.78233608938;44085.33871991858;44114.55885441104;44633.77683111564;43251.78374108235;43475;43802.561974379845;44785;44805.32792008057;43641.78179109084;44045;44221;44306.55693443024;44441;36222.63777362193;37598.248035039695;38645.420318695804;38785.6121438782;38957;29658;29725.256868578457;29790.25524361908;29935.55096673595;30020 06:13:36 INFO - PROCESS | 30586 | |2;jquery.html;408.24882216721244;416.43780895790707;421.2249460991775;424.56270041856897;438.4169055156642;18914;20623.89688051558;20736.89631551842;21432.03555839987;21544.461388465297;19406.805931940675;19795.505112372197;20221.292254771088;20280.695789563168;20298.492537686565;5810.651360918348;6070.939290607103;6079.331273559905;6080.2399700037495;6086.269647642289;5827.766889324422;6043.546733994946;6052.334243233241;6081.574289799716;6132.969335153318;6099.7865074722395;6136.140940268366;6142.477889379408;6147.323794382614;6147.569670123094;5957.9404205958035;6044.486218671418;6077.544184186204;6088.969555152196;6095.878082438348;5815.854603634901;5872.530197584183;5927.614705044159;5931.911021334683;5987.43119403656;15045.699086018329;15157.166355850424;20325.560932688026;24502.754972450402;25270.115545955803;2418.3954011497126;2445.76765207305;2446.522928029035;3018.7886847920654;3061.892833750808;10648.946755266174;10660.307080039773;11143.72140696489;11259.718507037389;11289.322640641587;1921.0874834453662;1990.164131064956;1994.700794880765;2002.2291417804104;2064.64900966836;62231;76688;76808.61595691986;81489.59255203686;86408.13591864126;10576.735581610368;10776.838347424795;11063.612773552886;11741;11862.940685296518;16625.3349866004;16633.750493742602;16645.167741613113;16648.500544983668;16654.833451665327;8140.185981401931;8481.660733570712;8518.446300990416;8584.656613735506;8682.91317086821;8075.313598344089;8096.473729207582;8131.59342032896;8274.420790544604;8297.58512074394;4901.166801643729;4955.925661115086;4961.82633607822;5054.721990290495;5074.391073071181;1959.8628096033426;1987.8509111816381;2015.4659015360942;2046.4167712201952;2083.7916208379042;943.7782121201512;998.5456617239139;1000.3497726477848;1039.5477967084344;1114.3035602748282;12243.755124897452;20771.58456830855;20798.58402831935;20890.268840590506;21512.924353782248;497.810831883889;503.0768539729564;520.5757307794136;532.810852147482;536.3028063517489;6577.210734711865;7013.824654383681;7027.332403421667;7038.120234970629;7077.716891324392;534163;554497;562178;581061;591662;65357.67321163459;92896;95462;95715;98603;521.3483146067416;530.0829564852826;530.273525270382;530.6975024236225;530.8991291654573;12103.878961210276;12156.149069565046;12157.27056376585;12312.384380780746;12387.318697471364;40086.79956600198;41681.58318416777;43224.783876080415;44255;46570;59863.10205346836;62530.687346564795;62715.6864215676;66846;67568.66215668891;8709.651613935372;8905.599248033983;9011.279097672039;9045.412048217107;9204.769880753034;10173;13317.86682133205;13505.662358441117;13556.322183890568;13951.441942322599;11800.881991179978;12029.639110826689;12061.216020958609;12113.091518136;12275;11724.94137529341;11760.294382337088;11962.700932476757;12016;12504.937475312565;12714.237145771283;12742.61772146837;12754.808677870025;12780.936095319465;12785.552505662257;9132.315076369432;9139.360244782802;9228.215601673934;9240.399374040935;9326.300527460604;9353.812923741623;12011.399430028638;12272;12287;12490.250584964931 06:13:36 INFO - PROCESS | 30586 | |3;mootools.html;1412.7103943691545;1514.182341535571;1541.9691606167878;1705.437205722112;1847.6951303034991;8695.869561956568;9145.679901203459;9185.770355741102;9381.81236375272;9382.437053776779;9137.497437640926;9209.033051529577;9269.212116970066;9271.768205794859;9276;2430.574649436346;2446;2447.302518782147;2449.301948944551;2457.8156638252112;2411.0076332106387;2460.5324988252264;2464.987675061622;2467.925962221127;2471.8146139039554;2452.3010941881566;2453.0800949643885;2453.386653336666;2455.042533411962;2476.789472894797;2396.0458804651485;2412.8552286862755;2423.430493833948;2430.829841911067;2449.4366295752075;2329.208069256444;2340.414896275931;2342.1802369170823;2345.401922509749;2371.1226846066893;9131.954340228256;9134.269258459308;9290.442573445614;9290.535473226311;9299.302552308603;5472.5621950243885;5704.429557044304;5712.914306285409;5713.028785106542;5716.314042314907;3642.071271825694;3647.069997150736;3684.097396137963;3691.870784522529;3707.480952666629;1191.1364260910857;1195.1514424758432;1200.2918278215895;1207.9335636539986;1275.475806411339;13007.609771706864;13050.543230986965;13095;13102.86897131026;13131.934340328236;4557.999999999934;4651.767411629405;4754.144254034307;4795.760211989386;4909.852704418873;10361.171106311476;10361.585536578603;10364.015418535228;10364.689059328231;10366.948165259126;5541.445855414427;5547.77808887648;5548.389677135512;5549.361823390281;5549.833504994856;4626.37543931565;4711.434627844681;4734.040169122642;5126.333576635014;5181.119209734355;4121.155163191538;4146.295129827876;4200.684948628865;4203.852865149705;4227.175700738327;1214.8117041858527;1216.1851559455185;1223.559518573313;1229.6126720083146;1231.3658465890073;913.2921985461188;1016.5730393234859;1035.7048241251357;1047.69093117529;1060.6977011551671;6160.199174107427;6262.749490020439;6319.6208227506495;6376.521760867953;6557;474.6985664103319;487.05268253247095;489.49826427911677;489.7110704684182;494.4165884256612;4439.644828413784;4528.864134075983;4534.591886730144;4568.74871882043;4582.266837306015;11398;11793.646190614296;11914.821277680841;11915;11919.880801191877;11751.941240293743;11752;11765.294082355085;11768.058555315554;11797.115216358807;8108.9594552026865;8409.369297302726;8424.115467875907;8611.052784193733;8630.956845215735;6282.968585157045;6320.1151838743535;6486.967565162144;6533.085368048382;6607.80176594703;10507.842382364117;10667.51996160159;10809.405482698212;10855.945720271347;10867.0762985144;9852.507374631383;10526.000029997138;10608.416537090534;11100.5559777608;11329.886701132884;8247.381446391424;8258.752237432887;8646.740597782076;8652.134786521297;8662.610182541926;9092.090790920854;9288.489133097744;9371.109744574254;9377.484238366955;9405.858912116188;7976.401769867399;8101.756947291591;8197.549134797644;8256.25693687571;8418.87371689413;8011.759647210593;8144.185581441809;8157.592120393838;8199.795005124919;8260.380471464545;7115.324044215791;7489.850202996018;7586.127595326608;7593.316601505891;7596.316331530189;5274.024305503494;5449.209864569694;5483.369412517612;5484.8354549363585;5502.862428439161;5222.817201397933;5461.317335333083;5519.613627046068;5526.253955715927;5545.944540554543 06:13:36 INFO - PROCESS | 30586 | |4;prototype.html;352.45193723759576;353.9805310707911;354.455910177877;357.728126623766;363.0578648407382;5532.695701736403;5536.640118392302;5537.58468114892;5560.52735517481;5570.582206334526;5481.671099734019;5488.17677348397;5537.612367134292;5546.86132846679;5548.833534993946;3879.3792993121106;4111.69162312827;4290.721103128302;4298.527162012192;4299.570042995691;4304.117655880537;4304.784760761965;4317.503487098977;4336.653067754588;4356.912861742763;4306.956930430702;4326.156399502082;4328.744664047423;4342.196693611692;4360;4077.9592204077876;4249.490061192646;4298.226319262531;4328.463395494601;4359.455068116501;3737.177820879402;3748.5689145748315;3759.56764972029;3760.2291530236507;3762.5108735864437;5303.337082864642;5308.973455132738;5310.840674779763;5327.6824581307255;5359.5444387227135;4653.302004699289;4872.731999740013;4876.902461950776;4881.389826271716;4933.629977751683;5749.59752817303;5751.309842818847;5768.163616275659;5769.711514424304;5785.537157027427;4160.313548264533;4175.352820312856;4176.206520761076;4178.477690288714;4181.979090104561;5810.360860305363;5947.583669143119;5952.57138286808;6006.069059295816;6043.969780151115;4249.235137675187;4264.424302719155;4276.700630955866;4281.165172791338;4290.549492303326;4332.588404101605;4336.501302350207;4340.197063543254;4352.542982986804;4353.82584696615;4660.951285960632;4900.705957642553;4964.180910149785;4977.477364876709;4978.65149439543;5737.971310143506;5743.971280143573;5757.366689664217;5766.913496297559;5782.3639399666;1469.1111877314072;1483.8961272711022;1491.9179445130621;1493.9775903361458;1498.6553092788502;909.8043920557112;957.4925289596525;959.7072892767773;966.1063516247583;975.5073687787622;4936.851894443173;4948.134076536664;4966.751662416865;4966.90066198674;4970.453250142481;5182.6372153949615;5194.194899790538;5218.165093585008;5249;5268.472143078541;5053.216751403538;5059.924101138486;5061.670991385548;5330.493603107699;5689.374168841424;3167.9683203168133;3172.175234439016;3176.444122278592;3181.2046988252937;3192.0902542775198;6004.5496587755215;6149.5080393568405;6176.474999625082;6178.505719542426;6227.346128656426;5685.687287199244;5946.791862284799;5959.165716799652;5986.880262394728;6038;5901.380355062743;5954.612950158225;6002.219711437485;6019.879602407927;6049.304330002018;5836.708164591839;5867.383924687846;5876.206712093813;5878.147668588116;5909.202257695156;5567.331920169606;5717.456841600041;5775.9133612996675;5777.8555536111935;5791.683326669197;5985.186962127793;6017.729202185825;6021.789237376671;6041.848953776015;6042.969785151046;4265.76538290397;4272.935905961475;4277.700560960703;4280.61474467287;4299.806508707178;5675.659460432387;5885.676287804212;5925.229720136527;5944.494717949023;6019.458248757633;5780.421957804186;5792.1311803229855;5809.50619197373;5840.795572154954;5846.093855452412;5567.24842146305;5733.684647344436;5754.791493786352;5773.24947756789;5780;5756.568257380797;5769.480746732815;5769.94230057694;5773.509251713652;5798.971005144947;5792.53659707214;5832.212651292021;5848;5864.912026319524;5909.143174239797;5457.536109430743;5686.545076393796;5721.713914304351;5722.62802917809;5743.913841292468 06:13:36 INFO - PROCESS | 30586 | |5;yui.html;731.9634018299087;755.1089714137318;756.2248695087535;777.5062835099711;891.8751374807532;15058.322375493102;16298.918505407486;16332.918335408322;16407.753883691756;16416.507504774847;15161.92419037906;15323.233838308068;15326.846731532682;15414;15417.15205663688;714.989275160873;838.3795990966671;849.6898631999337;865.3207232322628;868.4919322196523;869.656485688156;870.1472556894247;871.7994861181932;872.1801506583822;872.3936863879607;912.9315301352425;913.3058875254821;914.7987442762615;914.8399030169727;916.9587368568397;906.102958071511;906.1889608800153;906.7551761024553;907.9001309855944;909.6543313540878;670.2961890015513;670.3430637974764;670.497127154634;670.5205777868798;670.6881300195383;15194.696106077927;15312.540623781304;15424.614384640361;15466.30401631918;15470.149141797197;15016.174110423923;15113.471028514059;15229.543113706606;15336.923315383463;15788.131652759093;11933.94033029838;12113.454894529767;12160.452779624939;13435.731285374237;14588.27058647074;4609.354690343354;4653.976730116362;4743.478217396084;4762.976185119087;4865.878353041202;74716.25283747147;84321.57839210826;86620.56689716574;87310;88704.55647721721;11047.337159770439;11235.101191904629;11269.38018408979;11270.09839212861;11348.489317980711;18878.811211887707;19187.90406047961;19572.11925463329;20003.799962000485;21452.78547214539;8165.591720413956;8381.203785640355;8458.28104611115;8472.661093556311;8504;4841.05599408112;4985.102681517289;5092.414372347153;5130.820421285312;5167.199084141964;3542;3606.495090687306;3614.6746792788776;3620.05878471594;3629.854805807738;588.7880363069291;674.7874419557824;680.8706345794283;680.931906809325;681.7579759185517;681.5637991685326;715.2525610736792;738.5015114797491;741.4698490579261;764.6673696941848;9822.165115965223;10087.899121008842;10113.393196408239;10290.485475726184;10735.248532602644;647.0197650559312;664.226176504367;758.4122305213393;775.83707421441;785.2618538573837;4366.235908715962;4628.421447319085;4713.6936099153445;4743.95256047442;4750.572448479654;117699;123700.38149809373;125947.3702631481;127168;129514;111548;113347.43326283316;114362.42818785853;116465;116635.41682291534;98570;99254.5037274809;99373;101928.98071019343;102912;8678.652853886026;8688.261497772508;8866.64533418656;8877.600507977028;8947.150020748019;60091.39908601033;60767;61232.693836532315;61317;62582;82075.58962205151;87968;88116.1188388108;90704.5464772672;90723;11711.1802173847;11939.044876409693;11963.76072478563;12357.443915024025;12419.254844709347;11907.226030308002;12465.813012804996;12690;12691.682707932376;12723.109382343517;11707.531698731955;11935.522579097085;12172.330521821385;12375.443105060114;12571.74856502883;10938.72653183645;11767.293962362286;12574.245545267313;13546.187228766306;14196.929015354857;10469.738256543342;12983.480660773463;13934.372953216927;14317.140971541325;14799.630009249855;12244.451079924214;12559.309237991632;12666.873331266937;12873.871261287642;13171.143875648053;12191.207571507824;12240.143189976616;12350.258984460961;12361.876381236072;12731.872681273439 06:13:36 INFO - PROCESS | 30586 | __end_tp_report 06:13:36 INFO - PROCESS | 30586 | __start_cc_report 06:13:36 INFO - PROCESS | 30586 | _x_x_mozilla_cycle_collect,1036 06:13:36 INFO - PROCESS | 30586 | __end_cc_report 06:13:36 INFO - PROCESS | 30586 | __startTimestamp1477746816291__endTimestamp 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | ------- Summary: start ------- 06:13:36 INFO - PROCESS | 30586 | Number of tests: 6 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#0] dojo.html Cycles:165 Average:5524.81 Median:5733.05 stddev:625.09 (10.9%) stddev-sans-first:598.39 06:13:36 INFO - PROCESS | 30586 | Values: 3141.4 3160.4 3199.4 3219.1 3248.8 5500.3 5534.6 5586.1 5587.1 5591.3 5602.2 5662.2 5670.5 5683.4 5696.2 5099.9 5131.3 5176.9 5190.9 5195.3 4902.0 5189.8 5210.5 5219.3 5225.3 4887.3 4969.4 5040.5 5080.4 5088.0 5035.9 5051.1 5080.4 5152.8 5188.7 4991.6 5047.6 5106.4 5106.7 5117.7 5336.9 5384.9 5429.4 5453.2 5468.9 5885.4 5899.4 5924.1 5978.0 5979.6 5668.9 6015.5 6019.9 6034.5 6036.8 5465.7 5478.8 5542.8 5564.8 5586.6 6078.6 6082.4 6084.0 6085.5 6091.7 5532.2 5724.1 5868.1 5868.6 5870.3 5872.1 5929.7 5946.3 5982.5 5983.9 5907.2 5911.2 5913.5 5916.7 5917.7 5641.8 5775.8 5903.1 5910.5 5916.7 4539.2 4552.7 4561.6 4566.5 4587.7 4043.4 4045.5 4048.6 4053.7 4056.3 5477.5 5602.0 5664.9 5675.1 5680.6 5925.5 6017.8 6038.3 6060.8 6075.7 5727.7 5730.0 5735.1 5739.6 5745.1 4990.0 5192.4 5193.3 5199.8 5204.6 6096.1 6116.4 6128.5 6220.6 6233.6 6032.4 6034.9 6042.5 6045.1 6085.9 5990.1 6161.3 6235.8 6249.8 6249.9 5738.7 5756.5 5801.5 5837.5 5865.2 5917.8 5918.7 5923.5 5924.4 5928.9 5571.6 5797.7 5802.5 5804.9 5806.4 5722.0 5733.1 5747.9 5811.7 5848.6 5753.6 5770.5 5772.0 5785.4 5794.4 5630.6 5760.5 5766.9 5805.1 5811.3 5635.8 5713.1 5714.3 5757.2 5773.2 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#1] ext.html Cycles:180 Average:58683.95 Median:38715.52 stddev:115193.80 (297.5%) stddev-sans-first:115434.36 06:13:36 INFO - PROCESS | 30586 | Values: 586.0 643.6 658.8 665.1 677.0 34119.8 44755.1 45629.0 45646.0 45704.8 46691.5 47430.5 47443.8 47460.8 47466.5 8698.8 9317.3 9474.2 9477.4 11253.7 11013.2 11398.1 11407.1 11416.5 11417.5 11522.4 11576.2 11600.7 11605.2 11605.6 11445.0 11483.8 11495.9 11500.1 11500.4 11303.9 11338.4 11384.9 11389.9 11390.7 44106.3 44356.8 44395.1 44509.6 44517.1 3694.4 4131.2 4319.2 4360.8 4506.8 34282.3 34335.3 34472.3 35089.8 36591.6 4606.1 4739.0 4748.8 4782.0 4800.9 250188.0 250226.0 250359.0 251477.7 252854.0 57775.0 58240.7 58280.7 58294.0 58396.7 38813.8 39125.8 39428.2 40734.4 40754.8 16414.1 16503.8 16517.8 16535.2 16543.8 12434.8 12756.2 12776.2 12985.0 12988.2 19620.7 19834.3 20008.4 20769.3 20878.1 7093.9 7247.7 7341.2 7347.9 7456.5 1046.4 1081.7 1114.3 1145.8 1157.3 32229.2 33274.0 34474.5 34849.1 35117.5 1134.9 1155.9 1166.1 1182.9 1217.2 17004.9 18108.7 18296.8 18303.3 18439.3 569050.0 575672.0 576803.0 577646.0 579137.0 59593.7 59665.7 60026.4 60106.0 60115.4 1284.8 1329.6 1417.7 1430.1 1450.3 33816.0 35775.8 35848.0 35949.6 36194.6 52284.2 52874.7 52932.5 53108.5 53134.7 411549.0 411621.0 412081.0 412641.0 414082.0 27435.0 29207.6 30124.0 30177.5 31104.0 50030.5 50162.0 50238.7 50473.5 51124.7 43491.8 43532.8 44085.3 44114.6 44633.8 43251.8 43475.0 43802.6 44785.0 44805.3 43641.8 44045.0 44221.0 44306.6 44441.0 36222.6 37598.2 38645.4 38785.6 38957.0 29658.0 29725.3 29790.3 29935.6 30020.0 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#2] jquery.html Cycles:180 Average:31017.63 Median:45412.50 stddev:92814.99 (204.4%) stddev-sans-first:93046.90 06:13:36 INFO - PROCESS | 30586 | Values: 408.2 416.4 421.2 424.6 438.4 18914.0 20623.9 20736.9 21432.0 21544.5 19406.8 19795.5 20221.3 20280.7 20298.5 5810.7 6070.9 6079.3 6080.2 6086.3 5827.8 6043.5 6052.3 6081.6 6133.0 6099.8 6136.1 6142.5 6147.3 6147.6 5957.9 6044.5 6077.5 6089.0 6095.9 5815.9 5872.5 5927.6 5931.9 5987.4 15045.7 15157.2 20325.6 24502.8 25270.1 2418.4 2445.8 2446.5 3018.8 3061.9 10648.9 10660.3 11143.7 11259.7 11289.3 1921.1 1990.2 1994.7 2002.2 2064.6 62231.0 76688.0 76808.6 81489.6 86408.1 10576.7 10776.8 11063.6 11741.0 11862.9 16625.3 16633.8 16645.2 16648.5 16654.8 8140.2 8481.7 8518.4 8584.7 8682.9 8075.3 8096.5 8131.6 8274.4 8297.6 4901.2 4955.9 4961.8 5054.7 5074.4 1959.9 1987.9 2015.5 2046.4 2083.8 943.8 998.5 1000.3 1039.5 1114.3 12243.8 20771.6 20798.6 20890.3 21512.9 497.8 503.1 520.6 532.8 536.3 6577.2 7013.8 7027.3 7038.1 7077.7 534163.0 554497.0 562178.0 581061.0 591662.0 65357.7 92896.0 95462.0 95715.0 98603.0 521.3 530.1 530.3 530.7 530.9 12103.9 12156.1 12157.3 12312.4 12387.3 40086.8 41681.6 43224.8 44255.0 46570.0 59863.1 62530.7 62715.7 66846.0 67568.7 8709.7 8905.6 9011.3 9045.4 9204.8 10173.0 13317.9 13505.7 13556.3 13951.4 11800.9 12029.6 12061.2 12113.1 12275.0 11724.9 11760.3 11962.7 12016.0 12504.9 12714.2 12742.6 12754.8 12780.9 12785.6 9132.3 9139.4 9228.2 9240.4 9326.3 9353.8 12011.4 12272.0 12287.0 12490.3 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#3] mootools.html Cycles:180 Average:6129.25 Median:4681.60 stddev:3526.26 (75.3%) stddev-sans-first:3518.34 06:13:36 INFO - PROCESS | 30586 | Values: 1412.7 1514.2 1542.0 1705.4 1847.7 8695.9 9145.7 9185.8 9381.8 9382.4 9137.5 9209.0 9269.2 9271.8 9276.0 2430.6 2446.0 2447.3 2449.3 2457.8 2411.0 2460.5 2465.0 2467.9 2471.8 2452.3 2453.1 2453.4 2455.0 2476.8 2396.0 2412.9 2423.4 2430.8 2449.4 2329.2 2340.4 2342.2 2345.4 2371.1 9132.0 9134.3 9290.4 9290.5 9299.3 5472.6 5704.4 5712.9 5713.0 5716.3 3642.1 3647.1 3684.1 3691.9 3707.5 1191.1 1195.2 1200.3 1207.9 1275.5 13007.6 13050.5 13095.0 13102.9 13131.9 4558.0 4651.8 4754.1 4795.8 4909.9 10361.2 10361.6 10364.0 10364.7 10366.9 5541.4 5547.8 5548.4 5549.4 5549.8 4626.4 4711.4 4734.0 5126.3 5181.1 4121.2 4146.3 4200.7 4203.9 4227.2 1214.8 1216.2 1223.6 1229.6 1231.4 913.3 1016.6 1035.7 1047.7 1060.7 6160.2 6262.7 6319.6 6376.5 6557.0 474.7 487.1 489.5 489.7 494.4 4439.6 4528.9 4534.6 4568.7 4582.3 11398.0 11793.6 11914.8 11915.0 11919.9 11751.9 11752.0 11765.3 11768.1 11797.1 8109.0 8409.4 8424.1 8611.1 8631.0 6283.0 6320.1 6487.0 6533.1 6607.8 10507.8 10667.5 10809.4 10855.9 10867.1 9852.5 10526.0 10608.4 11100.6 11329.9 8247.4 8258.8 8646.7 8652.1 8662.6 9092.1 9288.5 9371.1 9377.5 9405.9 7976.4 8101.8 8197.5 8256.3 8418.9 8011.8 8144.2 8157.6 8199.8 8260.4 7115.3 7489.9 7586.1 7593.3 7596.3 5274.0 5449.2 5483.4 5484.8 5502.9 5222.8 5461.3 5519.6 5526.3 5545.9 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#4] prototype.html Cycles:180 Average:4814.20 Median:5484.92 stddev:1403.18 (25.6%) stddev-sans-first:1366.57 06:13:36 INFO - PROCESS | 30586 | Values: 352.5 354.0 354.5 357.7 363.1 5532.7 5536.6 5537.6 5560.5 5570.6 5481.7 5488.2 5537.6 5546.9 5548.8 3879.4 4111.7 4290.7 4298.5 4299.6 4304.1 4304.8 4317.5 4336.7 4356.9 4307.0 4326.2 4328.7 4342.2 4360.0 4078.0 4249.5 4298.2 4328.5 4359.5 3737.2 3748.6 3759.6 3760.2 3762.5 5303.3 5309.0 5310.8 5327.7 5359.5 4653.3 4872.7 4876.9 4881.4 4933.6 5749.6 5751.3 5768.2 5769.7 5785.5 4160.3 4175.4 4176.2 4178.5 4182.0 5810.4 5947.6 5952.6 6006.1 6044.0 4249.2 4264.4 4276.7 4281.2 4290.5 4332.6 4336.5 4340.2 4352.5 4353.8 4661.0 4900.7 4964.2 4977.5 4978.7 5738.0 5744.0 5757.4 5766.9 5782.4 1469.1 1483.9 1491.9 1494.0 1498.7 909.8 957.5 959.7 966.1 975.5 4936.9 4948.1 4966.8 4966.9 4970.5 5182.6 5194.2 5218.2 5249.0 5268.5 5053.2 5059.9 5061.7 5330.5 5689.4 3168.0 3172.2 3176.4 3181.2 3192.1 6004.5 6149.5 6176.5 6178.5 6227.3 5685.7 5946.8 5959.2 5986.9 6038.0 5901.4 5954.6 6002.2 6019.9 6049.3 5836.7 5867.4 5876.2 5878.1 5909.2 5567.3 5717.5 5775.9 5777.9 5791.7 5985.2 6017.7 6021.8 6041.8 6043.0 4265.8 4272.9 4277.7 4280.6 4299.8 5675.7 5885.7 5925.2 5944.5 6019.5 5780.4 5792.1 5809.5 5840.8 5846.1 5567.2 5733.7 5754.8 5773.2 5780.0 5756.6 5769.5 5769.9 5773.5 5799.0 5792.5 5832.2 5848.0 5864.9 5909.1 5457.5 5686.5 5721.7 5722.6 5743.9 06:13:36 INFO - PROCESS | 30586 | 06:13:36 INFO - PROCESS | 30586 | [#5] yui.html Cycles:180 Average:22760.21 Median:3998.05 stddev:34192.19 (855.2%) stddev-sans-first:34248.10 06:13:36 INFO - PROCESS | 30586 | Values: 732.0 755.1 756.2 777.5 891.9 15058.3 16298.9 16332.9 16407.8 16416.5 15161.9 15323.2 15326.8 15414.0 15417.2 715.0 838.4 849.7 865.3 868.5 869.7 870.1 871.8 872.2 872.4 912.9 913.3 914.8 914.8 917.0 906.1 906.2 906.8 907.9 909.7 670.3 670.3 670.5 670.5 670.7 15194.7 15312.5 15424.6 15466.3 15470.1 15016.2 15113.5 15229.5 15336.9 15788.1 11933.9 12113.5 12160.5 13435.7 14588.3 4609.4 4654.0 4743.5 4763.0 4865.9 74716.3 84321.6 86620.6 87310.0 88704.6 11047.3 11235.1 11269.4 11270.1 11348.5 18878.8 19187.9 19572.1 20003.8 21452.8 8165.6 8381.2 8458.3 8472.7 8504.0 4841.1 4985.1 5092.4 5130.8 5167.2 3542.0 3606.5 3614.7 3620.1 3629.9 588.8 674.8 680.9 680.9 681.8 681.6 715.3 738.5 741.5 764.7 9822.2 10087.9 10113.4 10290.5 10735.2 647.0 664.2 758.4 775.8 785.3 4366.2 4628.4 4713.7 4744.0 4750.6 117699.0 123700.4 125947.4 127168.0 129514.0 111548.0 113347.4 114362.4 116465.0 116635.4 98570.0 99254.5 99373.0 101929.0 102912.0 8678.7 8688.3 8866.6 8877.6 8947.2 60091.4 60767.0 61232.7 61317.0 62582.0 82075.6 87968.0 88116.1 90704.5 90723.0 11711.2 11939.0 11963.8 12357.4 12419.3 11907.2 12465.8 12690.0 12691.7 12723.1 11707.5 11935.5 12172.3 12375.4 12571.7 10938.7 11767.3 12574.2 13546.2 14196.9 10469.7 12983.5 13934.4 14317.1 14799.6 12244.5 12559.3 12666.9 12873.9 13171.1 12191.2 12240.1 12350.3 12361.9 12731.9 06:13:36 INFO - PROCESS | 30586 | -------- Summary: end -------- 06:13:36 INFO - PROCESS | 30586 | 06:13:37 INFO - TEST-INFO | 30586: exit 0 06:13:37 INFO - TEST-OK | dromaeo_css | took 1111685ms 06:13:37 INFO - TEST-START | kraken 06:13:37 INFO - operating with platform_type : linux_ 06:13:37 INFO - Initialising browser for kraken test... 06:13:37 INFO - TEST-INFO | started process 30769 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8c0BnD/profile http://localhost:38278/getInfo.html) 06:13:40 INFO - PROCESS | 30769 | __metrics Screen width/height:1600/1200 06:13:40 INFO - PROCESS | 30769 | colorDepth:24 06:13:40 INFO - PROCESS | 30769 | Browser inner width/height: 1024/697 06:13:40 INFO - PROCESS | 30769 | __metrics 06:13:41 INFO - PROCESS | 30769 | 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" 06:13:41 INFO - PROCESS | 30769 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:13:42 INFO - TEST-INFO | 30769: exit 0 06:13:42 INFO - Browser initialized. 06:13:42 INFO - Running cycle 1/1 for kraken test... 06:13:42 INFO - TEST-INFO | started process 30855 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8c0BnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:13:54 INFO - PROCESS | 30855 | RSS: Main: 143921152 06:13:54 INFO - PROCESS | 30855 | 06:13:56 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38278/tests/kraken/driver.html?testName=audio-beat-detection) 06:13:57 INFO - PROCESS | 30855 | RSS: Main: 199385088 06:13:57 INFO - PROCESS | 30855 | 06:13:59 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38278/tests/kraken/driver.html?testName=audio-dft) 06:14:00 INFO - PROCESS | 30855 | RSS: Main: 184377344 06:14:00 INFO - PROCESS | 30855 | 06:14:03 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38278/tests/kraken/driver.html?testName=audio-fft) 06:14:03 INFO - PROCESS | 30855 | RSS: Main: 275570688 06:14:03 INFO - PROCESS | 30855 | 06:14:05 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38278/tests/kraken/driver.html?testName=audio-oscillator) 06:14:06 INFO - PROCESS | 30855 | RSS: Main: 180764672 06:14:06 INFO - PROCESS | 30855 | 06:14:08 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:09 INFO - PROCESS | 30855 | RSS: Main: 179286016 06:14:09 INFO - PROCESS | 30855 | 06:14:12 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38278/tests/kraken/driver.html?testName=imaging-darkroom) 06:14:12 INFO - PROCESS | 30855 | RSS: Main: 214720512 06:14:12 INFO - PROCESS | 30855 | 06:14:16 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38278/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:16 INFO - PROCESS | 30855 | RSS: Main: 214183936 06:14:16 INFO - PROCESS | 30855 | 06:14:19 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38278/tests/kraken/driver.html?testName=json-parse-financial) 06:14:20 INFO - PROCESS | 30855 | RSS: Main: 213905408 06:14:20 INFO - PROCESS | 30855 | 06:14:21 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:22 INFO - PROCESS | 30855 | RSS: Main: 175853568 06:14:22 INFO - PROCESS | 30855 | 06:14:23 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:24 INFO - PROCESS | 30855 | RSS: Main: 183767040 06:14:24 INFO - PROCESS | 30855 | 06:14:26 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:27 INFO - PROCESS | 30855 | RSS: Main: 190435328 06:14:27 INFO - PROCESS | 30855 | 06:14:29 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:30 INFO - PROCESS | 30855 | RSS: Main: 197537792 06:14:30 INFO - PROCESS | 30855 | 06:14:32 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:14:33 INFO - PROCESS | 30855 | RSS: Main: 194375680 06:14:33 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | Cycle 1(1): loaded http://localhost:38278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38278/tests/kraken/driver.html?testName=ai-astar) 06:14:35 INFO - PROCESS | 30855 | RSS: Main: 244154368 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | __start_tp_report 06:14:35 INFO - PROCESS | 30855 | _x_x_mozilla_page_load 06:14:35 INFO - PROCESS | 30855 | _x_x_mozilla_page_load_details 06:14:35 INFO - PROCESS | 30855 | |i|pagename|runs| 06:14:35 INFO - PROCESS | 30855 | |0;ai-astar;111;111;119;109;112;109;109;110;111;111 06:14:35 INFO - PROCESS | 30855 | |1;audio-beat-detection;151;115;142;160;115;143;143;122;171;115 06:14:35 INFO - PROCESS | 30855 | |2;audio-dft;160;161;160;162;158;159;161;160;161;159 06:14:35 INFO - PROCESS | 30855 | |3;audio-fft;74;73;73;188;76;74;75;73;163;74 06:14:35 INFO - PROCESS | 30855 | |4;audio-oscillator;84;84;161;85;84;85;147;83;84;138 06:14:35 INFO - PROCESS | 30855 | |5;imaging-gaussian-blur;116;116;117;118;117;116;117;116;118;117 06:14:35 INFO - PROCESS | 30855 | |6;imaging-darkroom;146;146;145;145;146;146;146;146;146;146 06:14:35 INFO - PROCESS | 30855 | |7;imaging-desaturate;94;94;94;94;94;94;95;95;94;94 06:14:35 INFO - PROCESS | 30855 | |8;json-parse-financial;50;51;49;50;50;50;50;50;50;50 06:14:35 INFO - PROCESS | 30855 | |9;json-stringify-tinderbox;61;61;61;61;61;60;61;61;60;60 06:14:35 INFO - PROCESS | 30855 | |10;stanford-crypto-aes;76;72;74;72;78;73;74;73;74;73 06:14:35 INFO - PROCESS | 30855 | |11;stanford-crypto-ccm;123;118;123;117;118;127;118;124;158;119 06:14:35 INFO - PROCESS | 30855 | |12;stanford-crypto-pbkdf2;157;152;151;152;153;153;153;155;155;153 06:14:35 INFO - PROCESS | 30855 | |13;stanford-crypto-sha256-iterative;65;61;64;62;62;63;61;61;59;59 06:14:35 INFO - PROCESS | 30855 | __end_tp_report 06:14:35 INFO - PROCESS | 30855 | __start_cc_report 06:14:35 INFO - PROCESS | 30855 | _x_x_mozilla_cycle_collect,1659 06:14:35 INFO - PROCESS | 30855 | __end_cc_report 06:14:35 INFO - PROCESS | 30855 | __startTimestamp1477746875305__endTimestamp 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | ------- Summary: start ------- 06:14:35 INFO - PROCESS | 30855 | Number of tests: 14 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:2.94 (2.6%) stddev-sans-first:3.11 06:14:35 INFO - PROCESS | 30855 | Values: 111.0 111.0 119.0 109.0 112.0 109.0 109.0 110.0 111.0 111.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:143.00 stddev:20.14 (14.1%) stddev-sans-first:20.78 06:14:35 INFO - PROCESS | 30855 | Values: 151.0 115.0 142.0 160.0 115.0 143.0 143.0 122.0 171.0 115.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 06:14:35 INFO - PROCESS | 30855 | Values: 160.0 161.0 160.0 162.0 158.0 159.0 161.0 160.0 161.0 159.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#3] audio-fft Cycles:10 Average:94.30 Median:74.00 stddev:43.21 (58.4%) stddev-sans-first:45.20 06:14:35 INFO - PROCESS | 30855 | Values: 74.0 73.0 73.0 188.0 76.0 74.0 75.0 73.0 163.0 74.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#4] audio-oscillator Cycles:10 Average:103.50 Median:84.00 stddev:31.65 (37.7%) stddev-sans-first:32.77 06:14:35 INFO - PROCESS | 30855 | Values: 84.0 84.0 161.0 85.0 84.0 85.0 147.0 83.0 84.0 138.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 06:14:35 INFO - PROCESS | 30855 | Values: 116.0 116.0 117.0 118.0 117.0 116.0 117.0 116.0 118.0 117.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:14:35 INFO - PROCESS | 30855 | Values: 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:14:35 INFO - PROCESS | 30855 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 06:14:35 INFO - PROCESS | 30855 | Values: 50.0 51.0 49.0 50.0 50.0 50.0 50.0 50.0 50.0 50.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 06:14:35 INFO - PROCESS | 30855 | Values: 61.0 61.0 61.0 61.0 61.0 60.0 61.0 61.0 60.0 60.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:74.00 stddev:1.85 (2.5%) stddev-sans-first:1.80 06:14:35 INFO - PROCESS | 30855 | Values: 76.0 72.0 74.0 72.0 78.0 73.0 74.0 73.0 74.0 73.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:123.00 stddev:12.23 (9.9%) stddev-sans-first:12.96 06:14:35 INFO - PROCESS | 30855 | Values: 123.0 118.0 123.0 117.0 118.0 127.0 118.0 124.0 158.0 119.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.00 stddev:1.78 (1.2%) stddev-sans-first:1.32 06:14:35 INFO - PROCESS | 30855 | Values: 157.0 152.0 151.0 152.0 153.0 153.0 153.0 155.0 155.0 153.0 06:14:35 INFO - PROCESS | 30855 | 06:14:35 INFO - PROCESS | 30855 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.95 (3.1%) stddev-sans-first:1.66 06:14:35 INFO - PROCESS | 30855 | Values: 65.0 61.0 64.0 62.0 62.0 63.0 61.0 61.0 59.0 59.0 06:14:35 INFO - PROCESS | 30855 | -------- Summary: end -------- 06:14:35 INFO - PROCESS | 30855 | 06:14:36 INFO - TEST-INFO | 30855: exit 0 06:14:36 INFO - TEST-OK | kraken | took 59219ms 06:14:36 INFO - SUITE-END | took 1170s 06:14:36 INFO - Completed test suite (00:19:31) 06:14:36 INFO - javascript benchmark 06:14:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.403133404653, 3160.431122397969, 3199.3601279744043, 3219.114743445554, 3248.821314827685, 5500.312460942382, 5534.612577119603, 5586.078297080987, 5587.133994230896, 5591.30108736408, 5602.1596760485945, 5662.178984047321, 5670.518005969498, 5683.403242659523, 5696.202531645573, 5099.872503187413, 5131.255967884655, 5176.896462070757, 5190.896182076356, 5195.298634684326, 4901.950980490203, 5189.792408303663, 5210.5310522053005, 5219.321488206528, 5225.346831646044, 4887.2913427552985, 4969.428515720702, 5040.521150490697, 5080.364954380702, 5087.9745601272125, 5035.899282014376, 5051.06555287273, 5080.441151473335, 5152.793888244466, 5188.662736922088, 4991.575716064139, 5047.64666473347, 5106.412762532319, 5106.719130447824, 5117.692938423706, 5336.946630533684, 5384.865378365533, 5429.4299098594865, 5453.209284653703, 5468.863278418032, 5885.382034886361, 5899.439553242435, 5924.081767326072, 5978.0435130379365, 5979.611325263844, 5668.858278543069, 6015.488683461911, 6019.939800602026, 6034.456898879122, 6036.849078773022, 5465.726713664301, 5478.835634930959, 5542.806001789958, 5564.8052318169475, 5586.553075753889, 6078.574499784972, 6082.36135205797, 6084.026555751058, 6085.513158947274, 6091.725872335667, 5532.17017447378, 5724.141378793214, 5868.119782032729, 5868.647881127146, 5870.295564532284, 5872.148538461899, 5929.73316200772, 5946.316173640001, 5982.46157845796, 5983.9401605983385, 5907.202527658711, 5911.231539899872, 5913.526917846562, 5916.704164791743, 5917.6745279010065, 5641.774329026875, 5775.826725198251, 5903.144044113579, 5910.527157827363, 5916.7337469813965, 4539.228331183706, 4552.726836389761, 4561.566651168134, 4566.520515345841, 4587.7017993830295, 4043.4137050127565, 4045.4943132108488, 4048.554658987509, 4053.6554392876346, 4056.2698714231133, 5477.452254774491, 5602.0, 5664.8867022659315, 5675.148727690879, 5680.602357834912, 5925.466707996301, 6017.759289628453, 6038.30559485656, 6060.757569697251, 6075.696215189223, 5727.713614319267, 5730.025895597759, 5735.11105778605, 5739.62692424991, 5745.109508026263, 4990.0269447457395, 5192.350956130484, 5193.324867767166, 5199.766010529535, 5204.609654275944, 6096.055111457731, 6116.396640261158, 6128.533679014861, 6220.595661282002, 6233.625982441159, 6032.366601506778, 6034.879302414015, 6042.486388657014, 6045.1234570987845, 6085.939140608537, 5990.101484777144, 6161.260648722182, 6235.844103897258, 6249.843753906189, 6249.8750024998335, 5738.713064346849, 5756.453136951983, 5801.477866991822, 5837.474627283565, 5865.178874957594, 5917.763289468545, 5918.674472904032, 5923.526117910471, 5924.407559244213, 5928.881422371443, 5571.637843540157, 5797.652140871562, 5802.506786923159, 5804.854878627899, 5806.361300256884, 5722.0, 5733.0540460823495, 5747.885042299215, 5811.651300921958, 5848.590598658053, 5753.568482363923, 5770.451807078321, 5772.018756811436, 5785.363610002981, 5794.362620111869, 5630.5777066720975, 5760.452756988079, 5766.855828604318, 5805.129230615442, 5811.27359080115, 5635.774569017193, 5713.085906254979, 5714.342850572238, 5757.222774925499, 5773.22061521706], "value": 5482.618269777584, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.9765609375626, 643.5559463969861, 658.7694306992552, 665.0689035350508, 676.952613317068, 34119.82940085299, 44755.10489790206, 45629.0, 45646.0, 45704.77147614262, 46691.53308466914, 47430.52569474304, 47443.76278118613, 47460.76269618647, 47466.52533474664, 8698.826023479527, 9317.347785655007, 9474.194693451065, 9477.43135411874, 11253.66239012831, 11013.229073964825, 11398.145139114558, 11407.08743300534, 11416.486258118362, 11417.48621312043, 11522.423878806068, 11576.247543909662, 11600.651980440558, 11605.187636865423, 11605.593804216855, 11445.0, 11483.770324593545, 11495.942520287428, 11500.079993600491, 11500.367479788609, 11303.943480282629, 11338.376389298584, 11384.943075284653, 11389.943050284779, 11390.658280251606, 44106.338404923634, 44356.778216108716, 44395.11209775786, 44509.554904451186, 44517.109657806664, 3694.4458331250257, 4131.173765246933, 4319.200947824663, 4360.781960901974, 4506.819727210907, 34282.31435371278, 34335.31329373398, 34472.31055378904, 35089.82455087734, 36591.63408365909, 4606.147862645421, 4739.0, 4748.786304616266, 4781.976090119562, 4800.879978000543, 250188.0, 250226.0, 250359.0, 251477.7426112876, 252854.0, 57775.0, 58240.70879645574, 58280.708596456745, 58294.0, 58396.70801645965, 38813.805930970164, 39125.80437097796, 39428.2114357717, 40734.38898416526, 40754.79622601868, 16414.09722465252, 16503.83496165047, 16517.834821651628, 16535.173241338125, 16543.751843722355, 12434.75130497385, 12756.234625922474, 12776.169548979287, 12985.0, 12988.220706757436, 19620.705689414386, 19834.305799296955, 20008.39974800758, 20769.27307544229, 20878.060487278108, 7093.900445430966, 7247.710091596383, 7341.192468828424, 7347.8530429392185, 7456.545973535217, 1046.3930920066343, 1081.670090622352, 1114.2757207814984, 1145.8281257811395, 1157.3113997171838, 32229.194270143435, 33274.0, 34474.482882756776, 34849.12877178091, 35117.47323790145, 1134.9035331996708, 1155.9017483513828, 1166.0904494494307, 1182.8876256755595, 1217.2209785737136, 17004.914975425043, 18108.728369074473, 18296.817031829778, 18303.267869285344, 18439.26242950267, 569050.0, 575672.0, 576803.0, 577646.0, 579137.0, 59593.70203148956, 59665.70167149136, 60026.39973600208, 60106.0, 60115.39884601098, 1284.8137020132028, 1329.6077657091182, 1417.7164567086625, 1430.1061836352071, 1450.3400952566558, 33816.0, 35775.82112089423, 35848.0, 35949.64050359463, 36194.63805361913, 52284.21573676322, 52874.73562632162, 52932.470675294295, 53108.46891531035, 53134.73432632812, 411549.0, 411621.0, 412081.0, 412641.0, 414082.0, 27435.0, 29207.561886571293, 30124.0, 30177.54733678953, 31104.0, 50030.49969500258, 50162.0, 50238.74880625573, 50473.49526504688, 51124.74437627788, 43491.782541087094, 43532.78233608938, 44085.33871991858, 44114.55885441104, 44633.77683111564, 43251.78374108235, 43475.0, 43802.561974379845, 44785.0, 44805.32792008057, 43641.78179109084, 44045.0, 44221.0, 44306.55693443024, 44441.0, 36222.63777362193, 37598.248035039695, 38645.420318695804, 38785.6121438782, 38957.0, 29658.0, 29725.256868578457, 29790.25524361908, 29935.55096673595, 30020.0], "value": 21155.996483068528, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.24882216721244, 416.43780895790707, 421.2249460991775, 424.56270041856897, 438.4169055156642, 18914.0, 20623.89688051558, 20736.89631551842, 21432.03555839987, 21544.461388465297, 19406.805931940675, 19795.505112372197, 20221.292254771088, 20280.695789563168, 20298.492537686565, 5810.651360918348, 6070.939290607103, 6079.331273559905, 6080.2399700037495, 6086.269647642289, 5827.766889324422, 6043.546733994946, 6052.334243233241, 6081.574289799716, 6132.969335153318, 6099.7865074722395, 6136.140940268366, 6142.477889379408, 6147.323794382614, 6147.569670123094, 5957.9404205958035, 6044.486218671418, 6077.544184186204, 6088.969555152196, 6095.878082438348, 5815.854603634901, 5872.530197584183, 5927.614705044159, 5931.911021334683, 5987.43119403656, 15045.699086018329, 15157.166355850424, 20325.560932688026, 24502.754972450402, 25270.115545955803, 2418.3954011497126, 2445.76765207305, 2446.522928029035, 3018.7886847920654, 3061.892833750808, 10648.946755266174, 10660.307080039773, 11143.72140696489, 11259.718507037389, 11289.322640641587, 1921.0874834453662, 1990.164131064956, 1994.700794880765, 2002.2291417804104, 2064.64900966836, 62231.0, 76688.0, 76808.61595691986, 81489.59255203686, 86408.13591864126, 10576.735581610368, 10776.838347424795, 11063.612773552886, 11741.0, 11862.940685296518, 16625.3349866004, 16633.750493742602, 16645.167741613113, 16648.500544983668, 16654.833451665327, 8140.185981401931, 8481.660733570712, 8518.446300990416, 8584.656613735506, 8682.91317086821, 8075.313598344089, 8096.473729207582, 8131.59342032896, 8274.420790544604, 8297.58512074394, 4901.166801643729, 4955.925661115086, 4961.82633607822, 5054.721990290495, 5074.391073071181, 1959.8628096033426, 1987.8509111816381, 2015.4659015360942, 2046.4167712201952, 2083.7916208379042, 943.7782121201512, 998.5456617239139, 1000.3497726477848, 1039.5477967084344, 1114.3035602748282, 12243.755124897452, 20771.58456830855, 20798.58402831935, 20890.268840590506, 21512.924353782248, 497.810831883889, 503.0768539729564, 520.5757307794136, 532.810852147482, 536.3028063517489, 6577.210734711865, 7013.824654383681, 7027.332403421667, 7038.120234970629, 7077.716891324392, 534163.0, 554497.0, 562178.0, 581061.0, 591662.0, 65357.67321163459, 92896.0, 95462.0, 95715.0, 98603.0, 521.3483146067416, 530.0829564852826, 530.273525270382, 530.6975024236225, 530.8991291654573, 12103.878961210276, 12156.149069565046, 12157.27056376585, 12312.384380780746, 12387.318697471364, 40086.79956600198, 41681.58318416777, 43224.783876080415, 44255.0, 46570.0, 59863.10205346836, 62530.687346564795, 62715.6864215676, 66846.0, 67568.66215668891, 8709.651613935372, 8905.599248033983, 9011.279097672039, 9045.412048217107, 9204.769880753034, 10173.0, 13317.86682133205, 13505.662358441117, 13556.322183890568, 13951.441942322599, 11800.881991179978, 12029.639110826689, 12061.216020958609, 12113.091518136, 12275.0, 11724.94137529341, 11760.294382337088, 11962.700932476757, 12016.0, 12504.937475312565, 12714.237145771283, 12742.61772146837, 12754.808677870025, 12780.936095319465, 12785.552505662257, 9132.315076369432, 9139.360244782802, 9228.215601673934, 9240.399374040935, 9326.300527460604, 9353.812923741623, 12011.399430028638, 12272.0, 12287.0, 12490.250584964931], "value": 9016.855107933437, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.7103943691545, 1514.182341535571, 1541.9691606167878, 1705.437205722112, 1847.6951303034991, 8695.869561956568, 9145.679901203459, 9185.770355741102, 9381.81236375272, 9382.437053776779, 9137.497437640926, 9209.033051529577, 9269.212116970066, 9271.768205794859, 9276.0, 2430.574649436346, 2446.0, 2447.302518782147, 2449.301948944551, 2457.8156638252112, 2411.0076332106387, 2460.5324988252264, 2464.987675061622, 2467.925962221127, 2471.8146139039554, 2452.3010941881566, 2453.0800949643885, 2453.386653336666, 2455.042533411962, 2476.789472894797, 2396.0458804651485, 2412.8552286862755, 2423.430493833948, 2430.829841911067, 2449.4366295752075, 2329.208069256444, 2340.414896275931, 2342.1802369170823, 2345.401922509749, 2371.1226846066893, 9131.954340228256, 9134.269258459308, 9290.442573445614, 9290.535473226311, 9299.302552308603, 5472.5621950243885, 5704.429557044304, 5712.914306285409, 5713.028785106542, 5716.314042314907, 3642.071271825694, 3647.069997150736, 3684.097396137963, 3691.870784522529, 3707.480952666629, 1191.1364260910857, 1195.1514424758432, 1200.2918278215895, 1207.9335636539986, 1275.475806411339, 13007.609771706864, 13050.543230986965, 13095.0, 13102.86897131026, 13131.934340328236, 4557.999999999934, 4651.767411629405, 4754.144254034307, 4795.760211989386, 4909.852704418873, 10361.171106311476, 10361.585536578603, 10364.015418535228, 10364.689059328231, 10366.948165259126, 5541.445855414427, 5547.77808887648, 5548.389677135512, 5549.361823390281, 5549.833504994856, 4626.37543931565, 4711.434627844681, 4734.040169122642, 5126.333576635014, 5181.119209734355, 4121.155163191538, 4146.295129827876, 4200.684948628865, 4203.852865149705, 4227.175700738327, 1214.8117041858527, 1216.1851559455185, 1223.559518573313, 1229.6126720083146, 1231.3658465890073, 913.2921985461188, 1016.5730393234859, 1035.7048241251357, 1047.69093117529, 1060.6977011551671, 6160.199174107427, 6262.749490020439, 6319.6208227506495, 6376.521760867953, 6557.0, 474.6985664103319, 487.05268253247095, 489.49826427911677, 489.7110704684182, 494.4165884256612, 4439.644828413784, 4528.864134075983, 4534.591886730144, 4568.74871882043, 4582.266837306015, 11398.0, 11793.646190614296, 11914.821277680841, 11915.0, 11919.880801191877, 11751.941240293743, 11752.0, 11765.294082355085, 11768.058555315554, 11797.115216358807, 8108.9594552026865, 8409.369297302726, 8424.115467875907, 8611.052784193733, 8630.956845215735, 6282.968585157045, 6320.1151838743535, 6486.967565162144, 6533.085368048382, 6607.80176594703, 10507.842382364117, 10667.51996160159, 10809.405482698212, 10855.945720271347, 10867.0762985144, 9852.507374631383, 10526.000029997138, 10608.416537090534, 11100.5559777608, 11329.886701132884, 8247.381446391424, 8258.752237432887, 8646.740597782076, 8652.134786521297, 8662.610182541926, 9092.090790920854, 9288.489133097744, 9371.109744574254, 9377.484238366955, 9405.858912116188, 7976.401769867399, 8101.756947291591, 8197.549134797644, 8256.25693687571, 8418.87371689413, 8011.759647210593, 8144.185581441809, 8157.592120393838, 8199.795005124919, 8260.380471464545, 7115.324044215791, 7489.850202996018, 7586.127595326608, 7593.316601505891, 7596.316331530189, 5274.024305503494, 5449.209864569694, 5483.369412517612, 5484.8354549363585, 5502.862428439161, 5222.817201397933, 5461.317335333083, 5519.613627046068, 5526.253955715927, 5545.944540554543], "value": 4802.051551546735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.45193723759576, 353.9805310707911, 354.455910177877, 357.728126623766, 363.0578648407382, 5532.695701736403, 5536.640118392302, 5537.58468114892, 5560.52735517481, 5570.582206334526, 5481.671099734019, 5488.17677348397, 5537.612367134292, 5546.86132846679, 5548.833534993946, 3879.3792993121106, 4111.69162312827, 4290.721103128302, 4298.527162012192, 4299.570042995691, 4304.117655880537, 4304.784760761965, 4317.503487098977, 4336.653067754588, 4356.912861742763, 4306.956930430702, 4326.156399502082, 4328.744664047423, 4342.196693611692, 4360.0, 4077.9592204077876, 4249.490061192646, 4298.226319262531, 4328.463395494601, 4359.455068116501, 3737.177820879402, 3748.5689145748315, 3759.56764972029, 3760.2291530236507, 3762.5108735864437, 5303.337082864642, 5308.973455132738, 5310.840674779763, 5327.6824581307255, 5359.5444387227135, 4653.302004699289, 4872.731999740013, 4876.902461950776, 4881.389826271716, 4933.629977751683, 5749.59752817303, 5751.309842818847, 5768.163616275659, 5769.711514424304, 5785.537157027427, 4160.313548264533, 4175.352820312856, 4176.206520761076, 4178.477690288714, 4181.979090104561, 5810.360860305363, 5947.583669143119, 5952.57138286808, 6006.069059295816, 6043.969780151115, 4249.235137675187, 4264.424302719155, 4276.700630955866, 4281.165172791338, 4290.549492303326, 4332.588404101605, 4336.501302350207, 4340.197063543254, 4352.542982986804, 4353.82584696615, 4660.951285960632, 4900.705957642553, 4964.180910149785, 4977.477364876709, 4978.65149439543, 5737.971310143506, 5743.971280143573, 5757.366689664217, 5766.913496297559, 5782.3639399666, 1469.1111877314072, 1483.8961272711022, 1491.9179445130621, 1493.9775903361458, 1498.6553092788502, 909.8043920557112, 957.4925289596525, 959.7072892767773, 966.1063516247583, 975.5073687787622, 4936.851894443173, 4948.134076536664, 4966.751662416865, 4966.90066198674, 4970.453250142481, 5182.6372153949615, 5194.194899790538, 5218.165093585008, 5249.0, 5268.472143078541, 5053.216751403538, 5059.924101138486, 5061.670991385548, 5330.493603107699, 5689.374168841424, 3167.9683203168133, 3172.175234439016, 3176.444122278592, 3181.2046988252937, 3192.0902542775198, 6004.5496587755215, 6149.5080393568405, 6176.474999625082, 6178.505719542426, 6227.346128656426, 5685.687287199244, 5946.791862284799, 5959.165716799652, 5986.880262394728, 6038.0, 5901.380355062743, 5954.612950158225, 6002.219711437485, 6019.879602407927, 6049.304330002018, 5836.708164591839, 5867.383924687846, 5876.206712093813, 5878.147668588116, 5909.202257695156, 5567.331920169606, 5717.456841600041, 5775.9133612996675, 5777.8555536111935, 5791.683326669197, 5985.186962127793, 6017.729202185825, 6021.789237376671, 6041.848953776015, 6042.969785151046, 4265.76538290397, 4272.935905961475, 4277.700560960703, 4280.61474467287, 4299.806508707178, 5675.659460432387, 5885.676287804212, 5925.229720136527, 5944.494717949023, 6019.458248757633, 5780.421957804186, 5792.1311803229855, 5809.50619197373, 5840.795572154954, 5846.093855452412, 5567.24842146305, 5733.684647344436, 5754.791493786352, 5773.24947756789, 5780.0, 5756.568257380797, 5769.480746732815, 5769.94230057694, 5773.509251713652, 5798.971005144947, 5792.53659707214, 5832.212651292021, 5848.0, 5864.912026319524, 5909.143174239797, 5457.536109430743, 5686.545076393796, 5721.713914304351, 5722.62802917809, 5743.913841292468], "value": 4370.54958580397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.9634018299087, 755.1089714137318, 756.2248695087535, 777.5062835099711, 891.8751374807532, 15058.322375493102, 16298.918505407486, 16332.918335408322, 16407.753883691756, 16416.507504774847, 15161.92419037906, 15323.233838308068, 15326.846731532682, 15414.0, 15417.15205663688, 714.989275160873, 838.3795990966671, 849.6898631999337, 865.3207232322628, 868.4919322196523, 869.656485688156, 870.1472556894247, 871.7994861181932, 872.1801506583822, 872.3936863879607, 912.9315301352425, 913.3058875254821, 914.7987442762615, 914.8399030169727, 916.9587368568397, 906.102958071511, 906.1889608800153, 906.7551761024553, 907.9001309855944, 909.6543313540878, 670.2961890015513, 670.3430637974764, 670.497127154634, 670.5205777868798, 670.6881300195383, 15194.696106077927, 15312.540623781304, 15424.614384640361, 15466.30401631918, 15470.149141797197, 15016.174110423923, 15113.471028514059, 15229.543113706606, 15336.923315383463, 15788.131652759093, 11933.94033029838, 12113.454894529767, 12160.452779624939, 13435.731285374237, 14588.27058647074, 4609.354690343354, 4653.976730116362, 4743.478217396084, 4762.976185119087, 4865.878353041202, 74716.25283747147, 84321.57839210826, 86620.56689716574, 87310.0, 88704.55647721721, 11047.337159770439, 11235.101191904629, 11269.38018408979, 11270.09839212861, 11348.489317980711, 18878.811211887707, 19187.90406047961, 19572.11925463329, 20003.799962000485, 21452.78547214539, 8165.591720413956, 8381.203785640355, 8458.28104611115, 8472.661093556311, 8504.0, 4841.05599408112, 4985.102681517289, 5092.414372347153, 5130.820421285312, 5167.199084141964, 3542.0, 3606.495090687306, 3614.6746792788776, 3620.05878471594, 3629.854805807738, 588.7880363069291, 674.7874419557824, 680.8706345794283, 680.931906809325, 681.7579759185517, 681.5637991685326, 715.2525610736792, 738.5015114797491, 741.4698490579261, 764.6673696941848, 9822.165115965223, 10087.899121008842, 10113.393196408239, 10290.485475726184, 10735.248532602644, 647.0197650559312, 664.226176504367, 758.4122305213393, 775.83707421441, 785.2618538573837, 4366.235908715962, 4628.421447319085, 4713.6936099153445, 4743.95256047442, 4750.572448479654, 117699.0, 123700.38149809373, 125947.3702631481, 127168.0, 129514.0, 111548.0, 113347.43326283316, 114362.42818785853, 116465.0, 116635.41682291534, 98570.0, 99254.5037274809, 99373.0, 101928.98071019343, 102912.0, 8678.652853886026, 8688.261497772508, 8866.64533418656, 8877.600507977028, 8947.150020748019, 60091.39908601033, 60767.0, 61232.693836532315, 61317.0, 62582.0, 82075.58962205151, 87968.0, 88116.1188388108, 90704.5464772672, 90723.0, 11711.1802173847, 11939.044876409693, 11963.76072478563, 12357.443915024025, 12419.254844709347, 11907.226030308002, 12465.813012804996, 12690.0, 12691.682707932376, 12723.109382343517, 11707.531698731955, 11935.522579097085, 12172.330521821385, 12375.443105060114, 12571.74856502883, 10938.72653183645, 11767.293962362286, 12574.245545267313, 13546.187228766306, 14196.929015354857, 10469.738256543342, 12983.480660773463, 13934.372953216927, 14317.140971541325, 14799.630009249855, 12244.451079924214, 12559.309237991632, 12666.873331266937, 12873.871261287642, 13171.143875648053, 12191.207571507824, 12240.143189976616, 12350.258984460961, 12361.876381236072, 12731.872681273439], "value": 7863.0274938631665, "unit": "score"}], "name": "dromaeo_css", "value": 7461.832490395677, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 119.0, 109.0, 112.0, 109.0, 109.0, 110.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 142.0, 160.0, 115.0, 143.0, 143.0, 122.0, 171.0, 115.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 162.0, 158.0, 159.0, 161.0, 160.0, 161.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 188.0, 76.0, 74.0, 75.0, 73.0, 163.0, 74.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 161.0, 85.0, 84.0, 85.0, 147.0, 83.0, 84.0, 138.0], "value": 103.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 118.0, 117.0, 116.0, 117.0, 116.0, 118.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 49.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 74.0, 72.0, 78.0, 73.0, 74.0, 73.0, 74.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 118.0, 123.0, 117.0, 118.0, 127.0, 118.0, 124.0, 158.0, 119.0], "value": 124.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 151.0, 152.0, 153.0, 153.0, 153.0, 155.0, 155.0, 153.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 64.0, 62.0, 62.0, 63.0, 61.0, 61.0, 59.0, 59.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1487.8000000000002, "alertThreshold": 2.0}]} 06:14:36 INFO - Return code: 0 06:14:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:14:36 INFO - # TBPL SUCCESS # 06:14:36 INFO - Running post-action listener: _resource_record_post_action 06:14:36 INFO - [mozharness: 2016-10-29 13:14:36.594751Z] Finished run-tests step (success) 06:14:36 INFO - Running post-run listener: _resource_record_post_run 06:14:36 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 279089152; Read time: 36; Write time: 223264 06:14:36 INFO - TinderboxPrint: CPU usage
12.9% 06:14:36 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 36 06:14:36 INFO - TinderboxPrint: I/O write bytes / time
279,089,152 / 223,264 06:14:36 INFO - TinderboxPrint: CPU idle
8,239.0 (87.1%) 06:14:36 INFO - TinderboxPrint: CPU user
1,179.2 (12.5%) 06:14:36 INFO - TinderboxPrint: Swap in / out
0 / 0 06:14:36 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71315456; Read time: 0; Write time: 80768 06:14:36 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 207773696; Read time: 0; Write time: 142496 06:14:37 INFO - Running post-run listener: _upload_blobber_files 06:14:37 INFO - Blob upload gear active. 06:14:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:37 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 06:14:37 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'] 06:14:37 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 06:14:37 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:14:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:14:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:38 INFO - (blobuploader) - INFO - Done attempting. 06:14:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:14:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:42 INFO - (blobuploader) - INFO - Done attempting. 06:14:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:14:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:42 INFO - (blobuploader) - INFO - Done attempting. 06:14:42 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:42 INFO - Return code: 0 06:14:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/851bacfa5558a2663fb92b53ec5ff612956b06b07c1f3d6270100ca4cb70721db9f57f43be7013a3c73910c3875e5dc46da41c5f8c59f2e61320828b4883af9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f866b29de5f5f6a6524d1e5671884e89be7225d8eaf3e66e0499bd50c2a71f37c3532dfe2862ab53192ed6a6ce15b8c462e8f4065c83f44e9e881f6c4ee8470e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01f56c6028b7b6466d4a0b3da29bd461ad91f6422fc0102b675e6fdab7b7a3f90c820f631ccb1444dcda385a6af9f04fa34f55d4596bbc688fc5ec2c8399d159"} 06:14:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:42 INFO - Contents: 06:14:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/851bacfa5558a2663fb92b53ec5ff612956b06b07c1f3d6270100ca4cb70721db9f57f43be7013a3c73910c3875e5dc46da41c5f8c59f2e61320828b4883af9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f866b29de5f5f6a6524d1e5671884e89be7225d8eaf3e66e0499bd50c2a71f37c3532dfe2862ab53192ed6a6ce15b8c462e8f4065c83f44e9e881f6c4ee8470e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01f56c6028b7b6466d4a0b3da29bd461ad91f6422fc0102b675e6fdab7b7a3f90c820f631ccb1444dcda385a6af9f04fa34f55d4596bbc688fc5ec2c8399d159"} 06:14:42 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:42 INFO - Copying logs to upload dir... 06:14:42 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.091709 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-29 06:14:42.311252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 06:14:42.314746) ========= 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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 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/851bacfa5558a2663fb92b53ec5ff612956b06b07c1f3d6270100ca4cb70721db9f57f43be7013a3c73910c3875e5dc46da41c5f8c59f2e61320828b4883af9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f866b29de5f5f6a6524d1e5671884e89be7225d8eaf3e66e0499bd50c2a71f37c3532dfe2862ab53192ed6a6ce15b8c462e8f4065c83f44e9e881f6c4ee8470e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01f56c6028b7b6466d4a0b3da29bd461ad91f6422fc0102b675e6fdab7b7a3f90c820f631ccb1444dcda385a6af9f04fa34f55d4596bbc688fc5ec2c8399d159"} build_url:https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/b_-rRkX-QOCRrCSS4NoabQ/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/851bacfa5558a2663fb92b53ec5ff612956b06b07c1f3d6270100ca4cb70721db9f57f43be7013a3c73910c3875e5dc46da41c5f8c59f2e61320828b4883af9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f866b29de5f5f6a6524d1e5671884e89be7225d8eaf3e66e0499bd50c2a71f37c3532dfe2862ab53192ed6a6ce15b8c462e8f4065c83f44e9e881f6c4ee8470e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01f56c6028b7b6466d4a0b3da29bd461ad91f6422fc0102b675e6fdab7b7a3f90c820f631ccb1444dcda385a6af9f04fa34f55d4596bbc688fc5ec2c8399d159"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 06:14:42.339637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 06:14:42.339934) ========= 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=ed14279e38b05397cf4aa391000001a4-1477741227.565454-1570246928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 06:14:42.422175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 06:14:42.422509) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 06:14:42.422788) ========= ========= Total master_lag: 0.16 =========