builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1477916743.77 results: success (0) buildid: 20161031022521 builduid: 1450775bf61d47a490b6283ef9fef182 revision: 72abda1585a882494a9396a62648fe5c3132993b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.768719) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.769180) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.769489) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.784248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.784538) ========= 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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-31 05:25:44-- 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% 45.4M=0s 2016-10-31 05:25:44 (45.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078063 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.876705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.876990) ========= 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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014843 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:43.931662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 05:25:43.931993) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 72abda1585a882494a9396a62648fe5c3132993b --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 72abda1585a882494a9396a62648fe5c3132993b --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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-31 05:25:44,410 truncating revision to first 12 chars 2016-10-31 05:25:44,410 Setting DEBUG logging. 2016-10-31 05:25:44,410 attempt 1/10 2016-10-31 05:25:44,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/72abda1585a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 05:25:46,232 unpacking tar archive at: mozilla-beta-72abda1585a8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.237843 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 05:25:46.184025) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:46.184397) ========= script_repo_revision: 72abda1585a882494a9396a62648fe5c3132993b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:46.184861) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:46.185267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 05:25:46.195122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-10-31 05:25:46.195447) ========= /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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:25:46 INFO - MultiFileLogger online at 20161031 05:25:46 in /builds/slave/test 05:25:46 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:25:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:25:46 INFO - {'append_to_log': False, 05:25:46 INFO - 'base_work_dir': '/builds/slave/test', 05:25:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:25:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:25:46 INFO - 'branch': 'Mozilla-Beta', 05:25:46 INFO - 'buildbot_json_path': 'buildprops.json', 05:25:46 INFO - 'config_files': ('talos/linux_config.py',), 05:25:46 INFO - 'default_actions': ('clobber', 05:25:46 INFO - 'read-buildbot-config', 05:25:46 INFO - 'download-and-extract', 05:25:46 INFO - 'populate-webroot', 05:25:46 INFO - 'create-virtualenv', 05:25:46 INFO - 'install', 05:25:46 INFO - 'run-tests'), 05:25:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:25:46 INFO - 'download_minidump_stackwalk': True, 05:25:46 INFO - 'download_symbols': 'ondemand', 05:25:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:25:46 INFO - 'tooltool.py': '/tools/tooltool.py', 05:25:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:25:46 INFO - '/tools/misc-python/virtualenv.py')}, 05:25:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:25:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:25:46 INFO - 'installer_path': 'installer.exe', 05:25:46 INFO - 'log_level': 'info', 05:25:46 INFO - 'log_name': 'talos', 05:25:46 INFO - 'log_to_console': True, 05:25:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:25:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:25:46 INFO - 'opt_config_files': (), 05:25:46 INFO - 'pip_index': False, 05:25:46 INFO - 'sps_profile': False, 05:25:46 INFO - 'sps_profile_interval': 0, 05:25:46 INFO - 'suite': 'dromaeojs', 05:25:46 INFO - 'system_bits': '32', 05:25:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:25:46 INFO - 'title': 'talos-linux64-ix-009', 05:25:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:25:46 INFO - 'use_talos_json': True, 05:25:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:25:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:25:46 INFO - 'work_dir': 'build'} 05:25:46 INFO - [mozharness: 2016-10-31 12:25:46.766124Z] Running clobber step. 05:25:46 INFO - Running pre-action listener: _resource_record_pre_action 05:25:46 INFO - Running main action method: clobber 05:25:46 INFO - rmtree: /builds/slave/test/build 05:25:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:47 INFO - Running post-action listener: _resource_record_post_action 05:25:47 INFO - [mozharness: 2016-10-31 12:25:47.217834Z] Finished clobber step (success) 05:25:47 INFO - [mozharness: 2016-10-31 12:25:47.217935Z] Running read-buildbot-config step. 05:25:47 INFO - Running pre-action listener: _resource_record_pre_action 05:25:47 INFO - Running main action method: read_buildbot_config 05:25:47 INFO - Using buildbot properties: 05:25:47 INFO - { 05:25:47 INFO - "project": "", 05:25:47 INFO - "product": "firefox", 05:25:47 INFO - "script_repo_revision": "production", 05:25:47 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:25:47 INFO - "repository": "", 05:25:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:25:47 INFO - "buildid": "20161031022521", 05:25:47 INFO - "pgo_build": "True", 05:25:47 INFO - "basedir": "/builds/slave/test", 05:25:47 INFO - "buildnumber": 457, 05:25:47 INFO - "slavename": "talos-linux64-ix-009", 05:25:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:25:47 INFO - "platform": "ubuntu64_hw", 05:25:47 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:25:47 INFO - "branch": "mozilla-beta", 05:25:47 INFO - "repo_path": "releases/mozilla-beta", 05:25:47 INFO - "revision": "72abda1585a882494a9396a62648fe5c3132993b", 05:25:47 INFO - "stage_platform": "linux64-pgo", 05:25:47 INFO - "builduid": "1450775bf61d47a490b6283ef9fef182", 05:25:47 INFO - "slavebuilddir": "test" 05:25:47 INFO - } 05:25:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 05:25:47 INFO - Running post-action listener: _resource_record_post_action 05:25:47 INFO - [mozharness: 2016-10-31 12:25:47.220392Z] Finished read-buildbot-config step (success) 05:25:47 INFO - [mozharness: 2016-10-31 12:25:47.220482Z] Running download-and-extract step. 05:25:47 INFO - Running pre-action listener: _resource_record_pre_action 05:25:47 INFO - Running main action method: download_and_extract 05:25:47 INFO - mkdir: /builds/slave/test/build/tests 05:25:47 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:25:47 INFO - https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:25:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:25:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:25:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:25:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:25:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:25:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:25:47 INFO - retry: Failed, sleeping 30 seconds before retrying 05:26:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:26:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:26:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:26:17 INFO - retry: Failed, sleeping 60 seconds before retrying 05:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:27:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:27:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 INFO - Caught exception: HTTP Error 404: Not Found 05:27:17 INFO - Caught exception: HTTP Error 404: Not Found 05:27:17 INFO - Caught exception: HTTP Error 404: Not Found 05:27:17 INFO - trying https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:27:17 INFO - Downloading https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 INFO - Downloaded 1327 bytes. 05:27:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:27:17 INFO - Using the following test package requirements: 05:27:17 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:27:17 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:27:17 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 05:27:17 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'jsshell-linux-x86_64.zip'], 05:27:17 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:27:17 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:27:17 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 05:27:17 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 05:27:17 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:27:17 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:27:17 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:27:17 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:17 INFO - https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:27:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:27:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:27:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:27:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:27:17 INFO - retry: Failed, sleeping 30 seconds before retrying 05:27:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:27:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:27:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:27:48 INFO - retry: Failed, sleeping 60 seconds before retrying 05:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:28:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:28:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:48 INFO - Caught exception: HTTP Error 404: Not Found 05:28:48 INFO - Caught exception: HTTP Error 404: Not Found 05:28:48 INFO - Caught exception: HTTP Error 404: Not Found 05:28:48 INFO - trying https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:28:48 INFO - Downloading https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:48 INFO - Downloaded 21787849 bytes. 05:28:48 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:28:48 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:28:50 INFO - Return code: 0 05:28:50 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:28: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:28:50 INFO - https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:28:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:28:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:28:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:28:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:28:50 INFO - retry: Failed, sleeping 30 seconds before retrying 05:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:29:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:29:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:29:20 INFO - retry: Failed, sleeping 60 seconds before retrying 05:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:30:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:30:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:20 INFO - Caught exception: HTTP Error 404: Not Found 05:30:20 INFO - Caught exception: HTTP Error 404: Not Found 05:30:20 INFO - Caught exception: HTTP Error 404: Not Found 05:30:20 INFO - trying https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:30:20 INFO - Downloading https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:21 INFO - Downloaded 21787849 bytes. 05:30:21 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:30:21 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:30:22 INFO - Return code: 0 05:30:22 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:30:22 INFO - https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:30:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:30:22 INFO - retry: Failed, sleeping 30 seconds before retrying 05:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:30:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:30:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:30:52 INFO - retry: Failed, sleeping 60 seconds before retrying 05:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:31:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:31:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:52 INFO - Caught exception: HTTP Error 404: Not Found 05:31:52 INFO - Caught exception: HTTP Error 404: Not Found 05:31:52 INFO - Caught exception: HTTP Error 404: Not Found 05:31:52 INFO - trying https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:31:52 INFO - Downloading https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:54 INFO - Downloaded 14413770 bytes. 05:31:54 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:31:54 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:31:54 INFO - Return code: 0 05:31:54 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:31:54 INFO - https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:31:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:31:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:31:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:31:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:31:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:31:54 INFO - retry: Failed, sleeping 30 seconds before retrying 05:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:32:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:32:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:32:24 INFO - retry: Failed, sleeping 60 seconds before retrying 05:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:33:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:33:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:33:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:33:24 INFO - Caught exception: HTTP Error 404: Not Found 05:33:24 INFO - Caught exception: HTTP Error 404: Not Found 05:33:24 INFO - Caught exception: HTTP Error 404: Not Found 05:33:24 INFO - trying https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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:33:26 INFO - Downloaded 56802186 bytes. 05:33:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:33:26 INFO - mkdir: /builds/slave/test/properties 05:33:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:33:26 INFO - Writing to file /builds/slave/test/properties/build_url 05:33:26 INFO - Contents: 05:33:26 INFO - build_url:https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:33:27 INFO - Running post-action listener: _resource_record_post_action 05:33:27 INFO - Running post-action listener: set_extra_try_arguments 05:33:27 INFO - [mozharness: 2016-10-31 12:33:27.477674Z] Finished download-and-extract step (success) 05:33:27 INFO - [mozharness: 2016-10-31 12:33:27.477916Z] Running populate-webroot step. 05:33:27 INFO - Running pre-action listener: _resource_record_pre_action 05:33:27 INFO - Running main action method: populate_webroot 05:33:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:33:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:33:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:33:27 INFO - '--tppagecycles', 05:33:27 INFO - '7'], 05:33:27 INFO - 'tests': ['tsvgm']}}, 05:33:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:33:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:33:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:33:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:33:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['damp', 'tps']}, 05:33:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'tests': ['damp', 'tps']}, 05:33:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['dromaeo_dom']}, 05:33:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:33:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['basic_compositor_video']}, 05:33:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:33:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['a11yr', 05:33:27 INFO - 'ts_paint', 05:33:27 INFO - 'tpaint', 05:33:27 INFO - 'sessionrestore', 05:33:27 INFO - 'sessionrestore_no_auto_restore', 05:33:27 INFO - 'tabpaint']}, 05:33:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['tsvgx', 05:33:27 INFO - 'tsvgr_opacity', 05:33:27 INFO - 'tart', 05:33:27 INFO - 'tscrollx', 05:33:27 INFO - 'cart']}, 05:33:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:33:27 INFO - 'tsvgr_opacity', 05:33:27 INFO - 'tart', 05:33:27 INFO - 'tscrollx', 05:33:27 INFO - 'cart']}, 05:33:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'talos_options': ['--disable-e10s'], 05:33:27 INFO - 'tests': ['tp5o']}, 05:33:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'tests': ['tp5o']}, 05:33:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'talos_options': ['--disable-e10s', 05:33:27 INFO - '--xperf_path', 05:33:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:27 INFO - 'tests': ['tp5n']}, 05:33:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:27 INFO - 'talos_options': ['--xperf_path', 05:33:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:27 INFO - 'tests': ['tp5n']}}, 05:33:27 INFO - 'talos.zip': {'path': '', 05:33:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:33:27 INFO - Running post-action listener: _resource_record_post_action 05:33:27 INFO - [mozharness: 2016-10-31 12:33:27.498798Z] Finished populate-webroot step (success) 05:33:27 INFO - [mozharness: 2016-10-31 12:33:27.498887Z] Running create-virtualenv step. 05:33:27 INFO - Running pre-action listener: _resource_record_pre_action 05:33:27 INFO - Running main action method: create_virtualenv 05:33:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:33:27 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:33:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:33:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:33:27 INFO - Using real prefix '/usr' 05:33:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:33:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:33:30 INFO - Installing pip.................done. 05:33:30 INFO - Return code: 0 05:33:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:33:30 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:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 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:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:30 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:33:30 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:33:30 INFO - Using env: {'DISPLAY': ':0', 05:33:30 INFO - 'HOME': '/home/cltbld', 05:33:30 INFO - 'LANG': 'en_US.UTF-8', 05:33:30 INFO - 'LANGUAGE': 'en_US:en', 05:33:30 INFO - 'LOGNAME': 'cltbld', 05:33:30 INFO - 'MAIL': '/var/mail/cltbld', 05:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:30 INFO - 'MOZ_NO_REMOTE': '1', 05:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:30 INFO - 'NO_EM_RESTART': '1', 05:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:30 INFO - 'PWD': '/builds/slave/test', 05:33:30 INFO - 'SHELL': '/bin/bash', 05:33:30 INFO - 'SHLVL': '1', 05:33:30 INFO - 'TERM': 'linux', 05:33:30 INFO - 'TMOUT': '86400', 05:33:30 INFO - 'USER': 'cltbld', 05:33:30 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:30 INFO - '_': '/tools/buildbot/bin/python'} 05:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:30 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:33:30 INFO - Cleaning up... 05:33:30 INFO - Return code: 0 05:33:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:33:30 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:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 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:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:30 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:33:30 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:33:30 INFO - Using env: {'DISPLAY': ':0', 05:33:30 INFO - 'HOME': '/home/cltbld', 05:33:30 INFO - 'LANG': 'en_US.UTF-8', 05:33:30 INFO - 'LANGUAGE': 'en_US:en', 05:33:30 INFO - 'LOGNAME': 'cltbld', 05:33:30 INFO - 'MAIL': '/var/mail/cltbld', 05:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:30 INFO - 'MOZ_NO_REMOTE': '1', 05:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:30 INFO - 'NO_EM_RESTART': '1', 05:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:30 INFO - 'PWD': '/builds/slave/test', 05:33:30 INFO - 'SHELL': '/bin/bash', 05:33:30 INFO - 'SHLVL': '1', 05:33:30 INFO - 'TERM': 'linux', 05:33:30 INFO - 'TMOUT': '86400', 05:33:30 INFO - 'USER': 'cltbld', 05:33:30 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:30 INFO - '_': '/tools/buildbot/bin/python'} 05:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:30 INFO - Downloading/unpacking psutil>=3.1.1 05:33:30 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:33:30 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:33:30 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:33:30 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:33:30 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:33:30 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:33:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:32 INFO - Installing collected packages: psutil 05:33:32 INFO - Running setup.py install for psutil 05:33:32 INFO - building 'psutil._psutil_linux' extension 05:33:32 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:33:32 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:33:32 INFO - building 'psutil._psutil_posix' extension 05:33:32 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:33:32 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:33:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:33 INFO - Successfully installed psutil 05:33:33 INFO - Cleaning up... 05:33:33 INFO - Return code: 0 05:33:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:33:33 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:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:33 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:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:33 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:33 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:33:33 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:33:33 INFO - Using env: {'DISPLAY': ':0', 05:33:33 INFO - 'HOME': '/home/cltbld', 05:33:33 INFO - 'LANG': 'en_US.UTF-8', 05:33:33 INFO - 'LANGUAGE': 'en_US:en', 05:33:33 INFO - 'LOGNAME': 'cltbld', 05:33:33 INFO - 'MAIL': '/var/mail/cltbld', 05:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:33 INFO - 'MOZ_NO_REMOTE': '1', 05:33:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:33 INFO - 'NO_EM_RESTART': '1', 05:33:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:33 INFO - 'PWD': '/builds/slave/test', 05:33:33 INFO - 'SHELL': '/bin/bash', 05:33:33 INFO - 'SHLVL': '1', 05:33:33 INFO - 'TERM': 'linux', 05:33:33 INFO - 'TMOUT': '86400', 05:33:33 INFO - 'USER': 'cltbld', 05:33:33 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:33 INFO - '_': '/tools/buildbot/bin/python'} 05:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:33:33 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:33:33 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:33:33 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:33:33 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:33:33 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:33:33 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:33:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:33:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:33:35 INFO - Installing collected packages: mozsystemmonitor 05:33:35 INFO - Running setup.py install for mozsystemmonitor 05:33:35 INFO - Successfully installed mozsystemmonitor 05:33:35 INFO - Cleaning up... 05:33:35 INFO - Return code: 0 05:33:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:33:35 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:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:35 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:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:35 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:35 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:33:35 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:33:35 INFO - Using env: {'DISPLAY': ':0', 05:33:35 INFO - 'HOME': '/home/cltbld', 05:33:35 INFO - 'LANG': 'en_US.UTF-8', 05:33:35 INFO - 'LANGUAGE': 'en_US:en', 05:33:35 INFO - 'LOGNAME': 'cltbld', 05:33:35 INFO - 'MAIL': '/var/mail/cltbld', 05:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:35 INFO - 'MOZ_NO_REMOTE': '1', 05:33:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:35 INFO - 'NO_EM_RESTART': '1', 05:33:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:35 INFO - 'PWD': '/builds/slave/test', 05:33:35 INFO - 'SHELL': '/bin/bash', 05:33:35 INFO - 'SHLVL': '1', 05:33:35 INFO - 'TERM': 'linux', 05:33:35 INFO - 'TMOUT': '86400', 05:33:35 INFO - 'USER': 'cltbld', 05:33:35 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:35 INFO - '_': '/tools/buildbot/bin/python'} 05:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:35 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:33:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:33:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:33:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:33:37 INFO - warning: no files found matching '*.txt' 05:33:37 INFO - no previously-included directories found matching 'build' 05:33:37 INFO - no previously-included directories found matching 'dist' 05:33:37 INFO - no previously-included directories found matching '.git*' 05:33:37 INFO - Installing collected packages: jsonschema, functools32 05:33:37 INFO - Running setup.py install for functools32 05:33:37 INFO - warning: no files found matching '*.txt' 05:33:37 INFO - no previously-included directories found matching 'build' 05:33:37 INFO - no previously-included directories found matching 'dist' 05:33:37 INFO - no previously-included directories found matching '.git*' 05:33:38 INFO - Successfully installed jsonschema functools32 05:33:38 INFO - Cleaning up... 05:33:38 INFO - Return code: 0 05:33:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:33:38 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:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 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:33:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:38 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:33:38 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:33:38 INFO - Using env: {'DISPLAY': ':0', 05:33:38 INFO - 'HOME': '/home/cltbld', 05:33:38 INFO - 'LANG': 'en_US.UTF-8', 05:33:38 INFO - 'LANGUAGE': 'en_US:en', 05:33:38 INFO - 'LOGNAME': 'cltbld', 05:33:38 INFO - 'MAIL': '/var/mail/cltbld', 05:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:38 INFO - 'MOZ_NO_REMOTE': '1', 05:33:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:38 INFO - 'NO_EM_RESTART': '1', 05:33:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:38 INFO - 'PWD': '/builds/slave/test', 05:33:38 INFO - 'SHELL': '/bin/bash', 05:33:38 INFO - 'SHLVL': '1', 05:33:38 INFO - 'TERM': 'linux', 05:33:38 INFO - 'TMOUT': '86400', 05:33:38 INFO - 'USER': 'cltbld', 05:33:38 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:38 INFO - '_': '/tools/buildbot/bin/python'} 05:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:33:38 INFO - Cleaning up... 05:33:38 INFO - Return code: 0 05:33:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:33:38 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:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 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:33:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:38 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:33:38 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:33:38 INFO - Using env: {'DISPLAY': ':0', 05:33:38 INFO - 'HOME': '/home/cltbld', 05:33:38 INFO - 'LANG': 'en_US.UTF-8', 05:33:38 INFO - 'LANGUAGE': 'en_US:en', 05:33:38 INFO - 'LOGNAME': 'cltbld', 05:33:38 INFO - 'MAIL': '/var/mail/cltbld', 05:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:38 INFO - 'MOZ_NO_REMOTE': '1', 05:33:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:38 INFO - 'NO_EM_RESTART': '1', 05:33:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:38 INFO - 'PWD': '/builds/slave/test', 05:33:38 INFO - 'SHELL': '/bin/bash', 05:33:38 INFO - 'SHLVL': '1', 05:33:38 INFO - 'TERM': 'linux', 05:33:38 INFO - 'TMOUT': '86400', 05:33:38 INFO - 'USER': 'cltbld', 05:33:38 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:38 INFO - '_': '/tools/buildbot/bin/python'} 05:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:33: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:33: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:33: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:33: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:33: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:33: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:33:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:33:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:41 INFO - Downloading docopt-0.6.1.tar.gz 05:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:41 INFO - Running setup.py install for blobuploader 05:33:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:33:41 INFO - Running setup.py install for requests 05:33:41 INFO - Running setup.py install for docopt 05:33:41 INFO - Successfully installed blobuploader requests docopt 05:33:41 INFO - Cleaning up... 05:33:41 INFO - Return code: 0 05:33:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:41 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:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:41 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:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:41 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:41 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:33:41 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:33:41 INFO - Using env: {'DISPLAY': ':0', 05:33:41 INFO - 'HOME': '/home/cltbld', 05:33:41 INFO - 'LANG': 'en_US.UTF-8', 05:33:41 INFO - 'LANGUAGE': 'en_US:en', 05:33:41 INFO - 'LOGNAME': 'cltbld', 05:33:41 INFO - 'MAIL': '/var/mail/cltbld', 05:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:41 INFO - 'MOZ_NO_REMOTE': '1', 05:33:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:41 INFO - 'NO_EM_RESTART': '1', 05:33:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:41 INFO - 'PWD': '/builds/slave/test', 05:33:41 INFO - 'SHELL': '/bin/bash', 05:33:41 INFO - 'SHLVL': '1', 05:33:41 INFO - 'TERM': 'linux', 05:33:41 INFO - 'TMOUT': '86400', 05:33:41 INFO - 'USER': 'cltbld', 05:33:41 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:41 INFO - '_': '/tools/buildbot/bin/python'} 05:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:42 INFO - Running setup.py (path:/tmp/pip-D3fMh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:42 INFO - Running setup.py (path:/tmp/pip-HQW73D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:42 INFO - Running setup.py (path:/tmp/pip-l6002m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:42 INFO - Running setup.py (path:/tmp/pip-yPCVzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:42 INFO - Running setup.py (path:/tmp/pip-z2NgVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:42 INFO - Running setup.py (path:/tmp/pip-3Wr9fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:42 INFO - Running setup.py (path:/tmp/pip-y1ZslL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:42 INFO - Running setup.py (path:/tmp/pip-r6YpuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:42 INFO - Running setup.py (path:/tmp/pip-UFxZw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:42 INFO - Running setup.py (path:/tmp/pip-49UaQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:42 INFO - Running setup.py (path:/tmp/pip-JEN7Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:42 INFO - Running setup.py (path:/tmp/pip-AQTVau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:42 INFO - Running setup.py (path:/tmp/pip-bdc7mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:42 INFO - Running setup.py (path:/tmp/pip-R3idkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:43 INFO - Running setup.py (path:/tmp/pip-4tZ7mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:43 INFO - Running setup.py (path:/tmp/pip-wMufYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:43 INFO - Running setup.py (path:/tmp/pip-9ABlzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:43 INFO - Running setup.py install for manifestparser 05:33:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:33:43 INFO - Running setup.py install for mozcrash 05:33:43 INFO - Running setup.py install for mozdebug 05:33:43 INFO - Running setup.py install for mozdevice 05:33:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:33:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:33:43 INFO - Running setup.py install for mozfile 05:33:43 INFO - Running setup.py install for mozhttpd 05:33:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:33:43 INFO - Running setup.py install for mozinfo 05:33:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:33:43 INFO - Running setup.py install for mozInstall 05:33:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Running setup.py install for mozleak 05:33:44 INFO - Running setup.py install for mozlog 05:33:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Running setup.py install for moznetwork 05:33:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Running setup.py install for mozprocess 05:33:44 INFO - Running setup.py install for mozprofile 05:33:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Running setup.py install for mozrunner 05:33:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:33:44 INFO - Running setup.py install for mozscreenshot 05:33:44 INFO - Running setup.py install for moztest 05:33:44 INFO - Running setup.py install for mozversion 05:33:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:33:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:45 INFO - Cleaning up... 05:33:45 INFO - Return code: 0 05:33:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:45 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:45 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:33:45 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:33:45 INFO - Using env: {'DISPLAY': ':0', 05:33:45 INFO - 'HOME': '/home/cltbld', 05:33:45 INFO - 'LANG': 'en_US.UTF-8', 05:33:45 INFO - 'LANGUAGE': 'en_US:en', 05:33:45 INFO - 'LOGNAME': 'cltbld', 05:33:45 INFO - 'MAIL': '/var/mail/cltbld', 05:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:45 INFO - 'MOZ_NO_REMOTE': '1', 05:33:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:45 INFO - 'NO_EM_RESTART': '1', 05:33:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:45 INFO - 'PWD': '/builds/slave/test', 05:33:45 INFO - 'SHELL': '/bin/bash', 05:33:45 INFO - 'SHLVL': '1', 05:33:45 INFO - 'TERM': 'linux', 05:33:45 INFO - 'TMOUT': '86400', 05:33:45 INFO - 'USER': 'cltbld', 05:33:45 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:45 INFO - '_': '/tools/buildbot/bin/python'} 05:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:45 INFO - Running setup.py (path:/tmp/pip-hUO4sB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:45 INFO - Running setup.py (path:/tmp/pip-YMBVhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:45 INFO - Running setup.py (path:/tmp/pip-WKW7rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:45 INFO - Running setup.py (path:/tmp/pip-6BpuQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:45 INFO - Running setup.py (path:/tmp/pip-31IBDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:45 INFO - Running setup.py (path:/tmp/pip-bceL4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:45 INFO - Running setup.py (path:/tmp/pip-N1hwu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:45 INFO - Running setup.py (path:/tmp/pip-WUt6TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:45 INFO - Running setup.py (path:/tmp/pip-R7Jge5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:45 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:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:45 INFO - Running setup.py (path:/tmp/pip-ovh7Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:46 INFO - Running setup.py (path:/tmp/pip-LwccKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:46 INFO - Running setup.py (path:/tmp/pip-hpzskZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:46 INFO - Running setup.py (path:/tmp/pip-w7hsRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:46 INFO - Running setup.py (path:/tmp/pip-57yDxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:46 INFO - Running setup.py (path:/tmp/pip-KiXOfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:46 INFO - Running setup.py (path:/tmp/pip-inoZ2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:46 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:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:46 INFO - Running setup.py (path:/tmp/pip-AEW8Td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:46 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:33:46 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:33:46 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:33:46 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:33:46 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:33:46 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:33:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:46 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:33:46 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:33:46 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:33:46 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:33:46 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:33:46 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:33:48 INFO - Downloading blessings-1.6.tar.gz 05:33:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:48 INFO - Installing collected packages: blessings 05:33:48 INFO - Running setup.py install for blessings 05:33:48 INFO - Successfully installed blessings 05:33:48 INFO - Cleaning up... 05:33:48 INFO - Return code: 0 05:33:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:33:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:48 INFO - Reading from file tmpfile_stdout 05:33:48 INFO - Current package versions: 05:33:48 INFO - argparse == 1.2.1 05:33:48 INFO - blessings == 1.6 05:33:48 INFO - blobuploader == 1.2.4 05:33:48 INFO - docopt == 0.6.1 05:33:48 INFO - functools32 == 3.2.3-2 05:33:48 INFO - jsonschema == 2.5.1 05:33:48 INFO - manifestparser == 1.1 05:33:48 INFO - mozInstall == 1.12 05:33:48 INFO - mozcrash == 0.17 05:33:48 INFO - mozdebug == 0.1 05:33:48 INFO - mozdevice == 0.48 05:33:48 INFO - mozfile == 1.2 05:33:48 INFO - mozhttpd == 0.7 05:33:48 INFO - mozinfo == 0.9 05:33:48 INFO - mozleak == 0.1 05:33:48 INFO - mozlog == 3.3 05:33:48 INFO - moznetwork == 0.27 05:33:48 INFO - mozprocess == 0.23 05:33:48 INFO - mozprofile == 0.28 05:33:48 INFO - mozrunner == 6.12 05:33:48 INFO - mozscreenshot == 0.1 05:33:48 INFO - mozsystemmonitor == 0.3 05:33:48 INFO - moztest == 0.7 05:33:48 INFO - mozversion == 1.4 05:33:48 INFO - psutil == 3.1.1 05:33:48 INFO - requests == 1.2.3 05:33:48 INFO - wsgiref == 0.1.2 05:33:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33: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:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33: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:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:48 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:48 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:33:48 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:33:48 INFO - Using env: {'DISPLAY': ':0', 05:33:48 INFO - 'HOME': '/home/cltbld', 05:33:48 INFO - 'LANG': 'en_US.UTF-8', 05:33:48 INFO - 'LANGUAGE': 'en_US:en', 05:33:48 INFO - 'LOGNAME': 'cltbld', 05:33:48 INFO - 'MAIL': '/var/mail/cltbld', 05:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:48 INFO - 'MOZ_NO_REMOTE': '1', 05:33:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:48 INFO - 'NO_EM_RESTART': '1', 05:33:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:48 INFO - 'PWD': '/builds/slave/test', 05:33:48 INFO - 'SHELL': '/bin/bash', 05:33:48 INFO - 'SHLVL': '1', 05:33:48 INFO - 'TERM': 'linux', 05:33:48 INFO - 'TMOUT': '86400', 05:33:48 INFO - 'USER': 'cltbld', 05:33:48 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:48 INFO - '_': '/tools/buildbot/bin/python'} 05:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 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:33:49 INFO - Cleaning up... 05:33:49 INFO - Return code: 0 05:33:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:49 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 0x1c17030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, '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': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', '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:33:49 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:33:49 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:33:49 INFO - Using env: {'DISPLAY': ':0', 05:33:49 INFO - 'HOME': '/home/cltbld', 05:33:49 INFO - 'LANG': 'en_US.UTF-8', 05:33:49 INFO - 'LANGUAGE': 'en_US:en', 05:33:49 INFO - 'LOGNAME': 'cltbld', 05:33:49 INFO - 'MAIL': '/var/mail/cltbld', 05:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:49 INFO - 'MOZ_NO_REMOTE': '1', 05:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:49 INFO - 'NO_EM_RESTART': '1', 05:33:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:49 INFO - 'PWD': '/builds/slave/test', 05:33:49 INFO - 'SHELL': '/bin/bash', 05:33:49 INFO - 'SHLVL': '1', 05:33:49 INFO - 'TERM': 'linux', 05:33:49 INFO - 'TMOUT': '86400', 05:33:49 INFO - 'USER': 'cltbld', 05:33:49 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:49 INFO - '_': '/tools/buildbot/bin/python'} 05:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:33:49 INFO - Cleaning up... 05:33:49 INFO - Return code: 0 05:33:49 INFO - Running post-action listener: _resource_record_post_action 05:33:49 INFO - Running post-action listener: _start_resource_monitoring 05:33:49 INFO - Starting resource monitoring. 05:33:49 INFO - [mozharness: 2016-10-31 12:33:49.408452Z] Finished create-virtualenv step (success) 05:33:49 INFO - [mozharness: 2016-10-31 12:33:49.408878Z] Running install step. 05:33:49 INFO - Running pre-action listener: _resource_record_pre_action 05:33:49 INFO - Running main action method: install 05:33:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:49 INFO - Reading from file tmpfile_stdout 05:33:49 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:33:49 INFO - Reading from file tmpfile_stdout 05:33:49 INFO - Output received: 05:33:49 INFO - Usage: mozinstall [options] installer 05:33:49 INFO - Options: 05:33:49 INFO - -h, --help show this help message and exit 05:33:49 INFO - -d DEST, --destination=DEST 05:33:49 INFO - Directory to install application into. [default: 05:33:49 INFO - "/builds/slave/test"] 05:33:49 INFO - --app=APP Application being installed. [default: firefox] 05:33:49 INFO - mkdir: /builds/slave/test/build/application 05:33:49 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:33:49 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:34:03 INFO - Reading from file tmpfile_stdout 05:34:03 INFO - Output received: 05:34:03 INFO - /builds/slave/test/build/application/firefox/firefox 05:34:03 INFO - Running post-action listener: _resource_record_post_action 05:34:03 INFO - [mozharness: 2016-10-31 12:34:03.987978Z] Finished install step (success) 05:34:03 INFO - [mozharness: 2016-10-31 12:34:03.988256Z] Running run-tests step. 05:34:03 INFO - Running pre-action listener: _resource_record_pre_action 05:34:03 INFO - Running main action method: run_tests 05:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:34:03 INFO - Python 2.7.3 05:34:03 INFO - Return code: 0 05:34:03 INFO - grabbing minidump binary from tooltool 05:34:03 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:34:03 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 0x1ed63b0>, '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 0x1ed72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:34:03 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:34:03 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:34:03 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:34:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:34:04 INFO - Return code: 0 05:34:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:34:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:34:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:34:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:34:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:34:04 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/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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:34:04 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/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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:34:04 INFO - Using env: {'DISPLAY': ':0', 05:34:04 INFO - 'HOME': '/home/cltbld', 05:34:04 INFO - 'LANG': 'en_US.UTF-8', 05:34:04 INFO - 'LANGUAGE': 'en_US:en', 05:34:04 INFO - 'LOGNAME': 'cltbld', 05:34:04 INFO - 'MAIL': '/var/mail/cltbld', 05:34:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:34:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:34:04 INFO - 'MOZ_NO_REMOTE': '1', 05:34:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:34:04 INFO - 'NO_EM_RESTART': '1', 05:34:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:34:04 INFO - 'PWD': '/builds/slave/test', 05:34:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:34:04 INFO - 'SHELL': '/bin/bash', 05:34:04 INFO - 'SHLVL': '1', 05:34:04 INFO - 'TERM': 'linux', 05:34:04 INFO - 'TMOUT': '86400', 05:34:04 INFO - 'USER': 'cltbld', 05:34:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096', 05:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:34:04 INFO - '_': '/tools/buildbot/bin/python'} 05:34:04 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/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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:34:04 INFO - mozversion application_buildid: 20161031022521 05:34:04 INFO - mozversion application_changeset: 72abda1585a882494a9396a62648fe5c3132993b 05:34:04 INFO - mozversion application_display_name: Firefox 05:34:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:34:04 INFO - mozversion application_name: Firefox 05:34:04 INFO - mozversion application_remotingname: firefox 05:34:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:34:04 INFO - mozversion application_vendor: Mozilla 05:34:04 INFO - mozversion application_version: 50.0 05:34:04 INFO - mozversion platform_buildid: 20161031022521 05:34:04 INFO - mozversion platform_changeset: 72abda1585a882494a9396a62648fe5c3132993b 05:34:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:34:04 INFO - mozversion platform_version: 50.0 05:34:04 INFO - using testdate: 1477917244 05:34:04 INFO - actual date: 1477917244 05:34:04 INFO - starting webserver on 'localhost:39763' 05:34:04 INFO - SUITE-START | Running 2 tests 05:34:04 INFO - TEST-START | dromaeo_css 05:34:04 INFO - operating with platform_type : linux_ 05:34:04 INFO - Initialising browser for dromaeo_css test... 05:34:04 INFO - TEST-INFO | started process 16772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5Tv1E/profile http://localhost:39763/getInfo.html) 05:34:08 INFO - PROCESS | 16772 | __metrics Screen width/height:1600/1200 05:34:08 INFO - PROCESS | 16772 | colorDepth:24 05:34:08 INFO - PROCESS | 16772 | Browser inner width/height: 1024/697 05:34:08 INFO - PROCESS | 16772 | __metrics 05:34:09 INFO - PROCESS | 16772 | 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:34:09 INFO - PROCESS | 16772 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:34:09 INFO - PROCESS | 16772 | console.error: PushService: 05:34:09 INFO - PROCESS | 16772 | stateChangeProcessEnqueue: Error transitioning state 05:34:09 INFO - PROCESS | 16772 | UnknownError 05:34:09 INFO - TEST-INFO | 16772: exit 0 05:34:09 INFO - Browser initialized. 05:34:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:34:09 INFO - TEST-INFO | started process 16859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5Tv1E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:21 INFO - PROCESS | 16859 | RSS: Main: 144871424 05:34:21 INFO - PROCESS | 16859 | 05:35:14 INFO - PROCESS | 16859 | 1477917314586 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:37:21 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39763/tests/dromaeo/cssquery-ext.html) 05:37:22 INFO - PROCESS | 16859 | RSS: Main: 246665216 05:37:22 INFO - PROCESS | 16859 | 05:40:24 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-ext.html (next: http://localhost:39763/tests/dromaeo/cssquery-jquery.html) 05:40:25 INFO - PROCESS | 16859 | RSS: Main: 235294720 05:40:25 INFO - PROCESS | 16859 | 05:43:27 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39763/tests/dromaeo/cssquery-mootools.html) 05:43:28 INFO - PROCESS | 16859 | RSS: Main: 178941952 05:43:28 INFO - PROCESS | 16859 | 05:46:30 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39763/tests/dromaeo/cssquery-prototype.html) 05:46:30 INFO - PROCESS | 16859 | RSS: Main: 176422912 05:46:30 INFO - PROCESS | 16859 | 05:49:33 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39763/tests/dromaeo/cssquery-yui.html) 05:49:33 INFO - PROCESS | 16859 | RSS: Main: 175570944 05:49:33 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | Cycle 1(1): loaded http://localhost:39763/tests/dromaeo/cssquery-yui.html (next: http://localhost:39763/tests/dromaeo/cssquery-dojo.html) 05:52:36 INFO - PROCESS | 16859 | RSS: Main: 264941568 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | __start_tp_report 05:52:36 INFO - PROCESS | 16859 | _x_x_mozilla_page_load 05:52:36 INFO - PROCESS | 16859 | _x_x_mozilla_page_load_details 05:52:36 INFO - PROCESS | 16859 | |i|pagename|runs| 05:52:36 INFO - PROCESS | 16859 | |0;dojo.html;3238.6761323867613;3250.918727031824;3264.7551433642484;3269.313444176723;3303.785253958494;5570.972145139273;5657.915131273029;5660.688662123582;5664.348599911006;5671.6880571568545;5718.571107166958;5783.826485205451;5787.47912687858;5792.333881603617;5796.217510636063;5194.71429071401;5207.3490813648295;5208.713520756357;5260.421353651095;5275.86810329741;5049.697018178903;5063.341765570471;5277.86805329866;5278.996990571798;5313.787448502056;5154.536091751742;5172.198309262051;5172.767225474844;5175.922361164567;5195.350581177353;5117.5650069744115;5122;5129.9230511542355;5143.691378517301;5200.609954253446;5113.105206588869;5158.432572417031;5192.221166824968;5192.818251361185;5266.894662106774;5791.276090488689;5795.3045634523705;5835.533157347402;5847.327557330919;5857.912131318034;5886.617369870987;5951.4048595140575;5997.640141591519;6014.909776353358;6191.195144631214;5968.045112781934;5987.161797348374;6008.24896887889;6073.089036644495;6091.025435930274;5687.175359572839;5689.0328644130595;5694.402087780765;5698.401667824902;5702.201691763156;5833.241678581757;5984.251968503937;6125.815438131368;6201.976673848854;6288.371162883766;6005.6997150142315;6032.939670603238;6037.396260374016;6042.154098426224;6042.274927008787;5861.70691465425;6108.602940808833;6109.816705498843;6118;6122.234720659918;6017.2779266488305;6062.514998800086;6076.240469941257;6145.9692701536205;6165.599236049642;6148.661823599745;6151.753929842756;6163.3528479509905;6169.105479705508;6172.043333283348;4490.730556166641;4708.458527269339;4709.95290047102;4714;4728.4798672146035;4087.529934057562;4124.8556300529335;4146.274401979642;4152.958470415318;4196.181744559843;5817.854553636108;5851.61964472308;5856.033754430557;5860.8534786629825;5861.589688721749;5986.4013598639785;6164.3835616438;6174.25908890936;6187.133801267826;6188.628682279078;5750.6262092963825;5805.6806875621405;5806.912896306559;5883.17635531026;5900.291964964232;5317.202419637086;5323.30796996388;5323.973380133075;5324.680519168863;5327.946720532823;6158.815235542851;6352.968235158887;6354.237491500957;6355.173827402408;6356.77751278703;6017.157597936205;6044.153818465506;6057.545684073624;6124.808163753463;6131.162586540493;6241.344658810759;6241.407066328691;6285.937140628718;6286.4342209201395;6292.528060395397;5888.411158884077;5964.7912323068485;5972.598652311108;5985.581009329305;6003.609765365237;6056.878862422815;6087.604305720114;6089.604175728564;6174.536909731698;6175.722092505938;6007.729652165751;6010.609310394811;6019.638821670714;6024.126501657148;6050.485708714808;5831.854203644943;5964.642121472725;5986;6031.577789554689;6044.667543285162;5814.156947242711;5842.503387211965;5854.268216472941;5910.52715782745;5923.822285331447;5952.642841429528;5969.970150149222;6031.969840150771;6038;6057.363976782551;5668.234788303526;5840.094785308284;5886.440788125121;5891.410858914074;5931.970340148272 05:52:36 INFO - PROCESS | 16859 | |1;ext.html;599.5892813422803;630.593267342564;644.4715333426586;652.0675434129196;653.8103949854542;33514;36631.267374652525;36664.083397915034;45238.77380613092;46361.768191159;46388.536114638846;46831.53168468314;46884.29673554891;46955;46959;9118.90881091189;9315.673951411702;9323.114304141096;9330.486823224719;9338.346315757899;8087.878681819778;10876.184286178528;11116.221864469491;11149.108071354272;11480.827787583194;11238.550457981672;11335.603253886116;11342.262752921033;11344.716382090432;11345.886541134607;11127.666170014912;11161.274517156358;11214.383208923506;11235.269707468988;11240.943795281053;11033.227674062819;11086.447897294385;11130.109591232682;11136.109111271078;11152.052075573505;44714.32928506075;45161.54838451639;45164;45208.547914520765;45227.32159017617;3675.3016926783826;3689.8893033209047;3792.829322680486;3950.032242100675;4019.678425725935;33925.660743392495;36514.81742591297;36626.63373366259;36680.08299792527;36819.23855710832;4432.423784907941;4469;4479.819328276927;4548.431446069241;4574.862754117348;385069;393759;394863;395455;396215;70179;70478.64760676163;70972;71095;71178.64410677913;40835.1832963345;41777;41797.37303940443;44398.778006109766;44441.33337999933;18657.626847462976;18918.52703682419;18920;18921.432357029313;19082.14130364137;13067.150635208682;14458.132512049311;14635.341409636592;14641.048331858396;14644.267786610626;21760.782392176192;22387.104515819512;22647;22938.885305573367;23137.652935205984;7881.487703299267;8130.349572034342;8193.862099639762;8388.706395276136;8464.444933128101;1065.9680209593726;1101.7906597746403;1127.402446851387;1176.1590462819127;1202.7834989701937;34794.13014674653;35297;35322;35395;35531.4670279946;1118.7370967822717;1162.54079638544;1204.4098391787961;1213.8664746877837;1266.4301064520819;17243.568910777332;18112.184951677205;18407.26370945174;18418.17118229683;18566.25734970613;610679;614420;618612.5346866364;619320;620315;63524.68237658782;64126.6793666038;64180.67909660422;64242;64243;1313.7897936330332;1411.1533080151826;1441.84139744628;1454.6363409147714;1458.8687018168416;33673.83163084169;36333.273334532634;36491.63508364883;36516.63483365239;36537.26925461529;54197.18704219361;60404.69797650984;62190.68904655448;62478.56367899809;67784;524269;527084;527250;529661;530018;40721.79639101904;45079.191698525916;45155.32267016063;45280.54719452763;49798;50946.74526627343;51456;51562.48437515727;51580.22629660633;51892.740536297075;43917.78041109774;44137.55862441463;44722.77638611786;45124.32313515365;45240.32139517976;44485.77757111194;45138.54861451475;45358;45403.318950215114;45849.66475000492;43976.78011609922;44028.33957490704;44246.336304956094;44470.3329450065;45277.32084018677;30154.84922575373;31946.84026579852;31954.20114497156;32036.19909502188;32137.357252854345;29039.088595515026;29117.417651647273;29658.851705741334;29935.4012919736;30090 05:52:36 INFO - PROCESS | 16859 | |2;jquery.html;415.58856731835493;431.5950994958228;433.3305043707472;433.72458488859564;444.47773865707785;18507.074646267683;19736.407907762754;20184.293549725764;20747.688784668204;20928.162873485082;19033.859413196842;19130.33043843466;19496.512587185327;19705.802941970574;19771.505712357197;5877.382874798138;6058.515318774509;6105.297890742577;6116.938830611703;6128.601640893327;5797.913031304533;5911.586188966774;5918.615290006157;5936.940630593704;5974.103884417351;5903.940960590382;5906.497947674453;5916.585838991272;5919;5919.112133180014;5842.678652674102;5876.91184632233;5952.851178720523;5954.345022047614;5955.672438015908;5271.789128434858;5317.654352467077;5332.33345831771;5361.08861493547;5369.48989845964;17136;17397.652046959116;18364.90817545917;23152.42118947023;23437.88281058601;2496.3010357100043;2509.2346834215555;2516.7357427470033;2720.319920019995;3225.177579717157;15138;15290.311935962909;15410.460633877872;15427;15540.378384864705;1985.9007049647605;2018.5559176981042;2024.5748392837522;2049.2929939170963;2157.6439887418555;54175.45824541783;59449.702751485966;70833;73483;74748.25251747413;11315.20793544444;11634.76730465403;11640.592579259684;11728.706782330339;11812.114091443176;16611.252493637814;16629.50111496657;16636.916815415847;16654.16729163561;16674.33302667904;8204.58977051157;8233.670653173926;8394.664213431517;8521.233089022018;8584.91415085841;7921.326687231534;7941.36469082472;8112.391570632226;8145.877811832827;8168.387370947203;4958.553730164303;5083.161278389024;5114.3095682083185;5125.923111153335;5203.557697595671;2030.4923769057737;2073.9377818665466;2097.559512502361;2114.9471263218234;2115.746110466723;1022.5602990714065;1042.5621239079605;1048.8846226915034;1051.6476980211562;1103.1285284625217;19831.107600158026;20096.799032009785;20134.29529966444;20323.390298291073;20399.28602498935;1025.4565080507193;1045.273534893242;1067.236925598201;1072.2226385870338;1094.8357746337954;10507.159427245802;11339.546418143182;11583.6524904253;11645.068394528418;11688.123390745728;468867;475800;481466;492916;493435;85995;89709.55145224363;90134.54932725294;90765.54617227004;90776.546117269;1018.7809620931536;1033.4625994482826;1046.7233766557706;1049.4280617063719;1050.7845891592206;17441.651166976844;17889.37387191442;18099.728504072187;18198.636027279645;18367.908160459112;41138.588614113476;41381;41622.37566436567;42392.36411453893;42477.36283955805;55947.440525594226;56244;56662.433375665714;57792.422075778704;58217;8365.372597055368;8961.103889611248;9037.954810225907;9112.908870911206;9296.488693121943;12671.936640316739;12840.935795320964;12859.614211573667;13571.932140339235;13816.792748108586;10620.681379558626;11218.719532011439;11274.37990910475;11297.43512824372;11699.473523691624;11156.665300041012;11635;12015.158938874545;12063.155579109378;12194.207376520499;11619.6514104577;11694.649160525198;11709.765804683688;11750.529978800752;11947.223430476992;9005.324600655109;9173.862392064259;9216.861747073664;9230.907690923004;9395.389299695498;11418.600348988079;11434.82847757301;11486.885131148916;11523.53905843757;11741.589044383747 05:52:36 INFO - PROCESS | 16859 | |3;mootools.html;1366.8428130764964;1513.2736286582438;1531.3950989359203;1690.7041267778143;1855.1280897977945;8262.215089566489;9322.906770932288;9536.237101031918;9568.904310956897;9929.453880036595;9696.466694331826;9736.70789876303;9759.658411955583;9777;9782.559784809682;2472.7774500295013;2513.6229565565127;2518.899244030237;2522.092046863128;2523;2439;2484.7391023942496;2490.5890528062846;2496.0764517128687;2498.775110240078;2462.2982450001755;2476.5418397596504;2523.40699935515;2524.823262371635;2527.494501099778;2408.7350391456926;2430.173740928084;2432.6351047342864;2433.7687919647606;2453.4970331082086;2235.1953296813135;2264.4565304327007;2267.6258417361114;2268.3421807675754;2306.630939049744;9564.091411315914;9746.756331091708;9841.90158098417;9856;9895.455749933752;5537.252470916415;5737.856553586152;5744.6840423776675;5755.194272801811;5779.711014449261;3460.1349662584353;3493.179102910814;3508.578970523528;3553.7690050146657;3555.0401391624373;1144.6165534545937;1150.7468356961456;1153.8326942593278;1177.4289469607234;1182.9885447942058;13571.59285221445;13609.183448993092;13652.385642646006;13699.041067125305;13761.105528140639;4466.240739074366;4650.372199753057;4726;4728.770519664912;4762.333273341735;10832.241743078066;10852.18608604342;10857.131429485622;10858.239923205458;10861.348319100722;5537.473939975695;5556.749946252347;5565.220869078333;5587.078132108157;5608.298962629671;4449.1991441540185;4601.585857272861;4870.197958477019;5109.3613298337705;5165.974170129125;4062.370332598452;4114.259433301974;4150.6471949884;4152.148809494107;4221.746695198237;1194.50428072349;1199.9820002699967;1203.7111093337535;1204.0307552420386;1209.625016244967;954.7279025477706;1071.49104175516;1073.039629531565;1074.6238816414193;1091.1925175370168;6476.838079048061;6531.804045878631;6571.967140164268;6718.093057437183;6725.529212955046;448.4439295273837;449.910017996402;457.599600349694;472.6502388232683;476.5020553521509;4558.270676691714;4594.264917613166;4653.790579423934;4704.1767690654;4792.161371759928;12105.092118091003;12107.39463026845;12382.81425778614;12401.317927514074;12600.684982875502;12438.626841194779;12446.128770986124;12450.688732781753;12453.501859925682;12463.563775268003;8744.344174186961;8951.686690965915;9022.323325750594;9274.30442716799;9311.813763724553;6621.3047629998155;6763.898541521783;6789.253182149861;6835.316468353125;6922.7923162305215;11089.112870970464;11102.944485277521;11354;11487.081033517466;11527.481263343001;10335.94832025835;10647.520861561403;11271.887281127083;11561.479733412185;11640.476178572155;8468.66125354979;8636.309095272238;8684.652613895627;8820.955895220482;9008.86486702713;9564.66523671668;9693.079157480028;9718.70843874685;9723.756906077404;9728.124468797841;8199.95900020496;8277.875831862648;8355.289800366794;8414.3268538518;8516.318694504302;8293;8307.501549907025;8349.165083491602;8354.916450835413;8454.788630284293;7729.690812367442;7755.457118001911;7781.5331080135375;7818.3354414875375;7881.566513841564;5854.063349864001;5896.9115463268945;5908.3205431375945;5923.348431672599;6007.94290662598;5952.821415357546;5953.464188223081;6040.305364883095;6066.181065556093;6082.847928801815 05:52:36 INFO - PROCESS | 16859 | |4;prototype.html;350.9947350789737;362.2826802930197;363.8617325416342;367.481850590667;370.279805777762;5483.8080667176655;5533.170024496322;5585.832425027255;5589.385167631557;5594.49649531542;5317.627766056377;5393.973030134854;5430.149171684868;5506.201600767887;5508.3114610673665;4318.157959197958;4351.978240108795;4354.259775838099;4379.2848433970985;4410.338449232609;4371.213181627306;4382.824687012516;4385.386045953569;4391;4421.712588681741;4244.617984381405;4337.718048326865;4364.585364390377;4366.934495982563;4387;4310.56894310568;4323.978380108079;4325.589069038437;4339.849939765964;4360.931571764938;3888.844446222148;3890.735510958921;3899.317619416619;3905.8437662493466;3920.372740361557;5407.486288802557;5423.2949716537;5451.04606693827;5451.64564303319;5464.781408743645;4842.733649649268;4975.676581022222;4991.850244492671;4995.5753760930365;5021.774020169101;5706.174024312222;5710.942890571083;5716.428357164292;5716.8856622867725;5722.914156287659;4265.38151967966;4267.445232119804;4307.612314891675;4319.654427645781;4328.632066274339;5760.683162426065;5816.680082595413;5821.708914554255;6082.1180928765525;6091;4323;4351.06452112791;4380.4305440292555;4380.759058251827;4383.473983121982;4427.889302767457;4432.312991486325;4434.135343607965;4436.401085853433;4441.7779111044965;4879.2925025871045;5018.824341148115;5019.247112933089;5028.270900719448;5042.924356134661;5872.677002764889;5878.794242201502;5881.176635271066;5937.584369094122;5944.117103204942;1505.33012809301;1510.7884896114554;1526.5725596832904;1529.258309719785;1530.8163020437396;944.617429940875;975.8536219567121;980.475360813927;982.7444864335181;1003.82934901067;4973.726445045557;4985.108636990537;5055.191169412877;5069.619778516626;5090.49095090488;5235.65968212065;5273.472652734696;5301.655392399482;5303.443138470482;5349.251104845324;4900.583450406755;5027.974860125749;5045.445001049881;5049.192129259301;5053.772580233898;2771.722827717212;2813.3529288263403;2814.8029637925556;2832.5326321156776;2833.2208642623445;5375.704336261543;5422.240886275925;5437.918431223535;5440.591955603346;5508.807191748269;5114.718690471986;5248.737563121828;5340.866478338073;5371.892562148813;5377.7311134443125;5404.918926216189;5439.700816455053;5445.918311225335;5449.41966829615;5469.644473109235;4921.753912304299;5261.316728468585;5279.260903473593;5300.197932702899;5331.680099194061;5165.586753059822;5184.7926082956255;5201.505856943584;5234.97382513085;5285.656432331886;5502.339719233718;5990.820275391745;6063.181470501426;6064.4535643410645;6184.567080304336;4311.762853042987;4319.235689159003;4331.660903112284;4348.130373925291;4358.716683415568;5802.593818432838;6004.369541198112;6046.848828779316;6139.079138129317;6143.692815359124;5855;5860.208871802252;5864.445921830804;5870.647761134345;5887.676177810262;5898.528117750656;5901.793437229677;5905.527557795452;5906.645601263938;5924.822255332347;5665.575081868794;5770.394108618702;5802.056311135817;5819.243498345188;5819.410362388866;5901.852453688693;5922.526197904243;5925;5942.12822960766;5952.315483719428;5471.616986811045;5707.6575405475805;5711.82907503969;5750.281214848144;5763.740631671669 05:52:36 INFO - PROCESS | 16859 | |5;yui.html;775.1937984496125;787.2953706432743;798.4011991006745;822.3051521464361;842.9704960326388;14620.34208460619;15613.063216207007;15658;15689.058656480605;15713.371465141396;14031.5088971886;14077.50728724495;14110.153390796533;14137;14145.151290922551;694.8054544727485;865.671045002898;872.97817554561;885.1900511032397;893.5755516129819;851.0808327006818;851.7189327521903;852.6802449081595;854.363499193099;854.5214679779343;923.4182465047011;924.2098006204666;925.7361651929201;926.4904302633558;926.847070233414;919.7102912582515;920.3419555018161;920.9631614735403;921.0099143420816;924.075924075924;674.0193019157115;679.7281087564965;680.4352387518397;681.3595220492721;682.6313790553095;13612;13612.931935340359;13623;13643.931780341134;13653.86346136536;14860.331285092198;15118.848811511854;15150;15173.089614623048;15293.541193764206;11319.886801131966;11352.205345625809;11469.139814513945;11665;11790.35153066581;3993.520777506718;4004.5194576650697;4021.195760847842;4166.729162604421;4194.3498757692605;82492;86801.13198867993;87694;88247.55876220578;88862.5556872218;11611.129165312635;11662.708432289091;11761.529538818522;11819.645410637522;11852.11109166816;21728.130874764833;21736.891315543322;21944.231951881928;21987.23044693428;22002.339929802132;8580.528070956032;8645.481271123752;8739.21347078766;8743.606537705818;9044.502552359552;5201.167813149954;5207.3490813648295;5213.6089793265655;5349.358077030781;5369.060414427538;3566.268914872445;3707.925841483155;3710.461983012448;3726.273376691519;3739.794311312904;638.6391688695873;642.4250295985067;650.0963660511892;650.3334082565314;659.9144407449718;632.276043929698;645.3320812958659;705.6154395854272;706.8798304288283;731.2175971710219;9285.303602229726;9451.243900487945;9623.951880240555;9872.210223182128;9954.203663706887;630.2877748144659;634.4987459906622;718.9352958233784;728.471857903016;754.1289810269147;4608.193566125915;4637.930431043537;4815.15734746425;4908.8772780680765;5018.623603229772;69687;69923.3007669927;69999.3000070003;111067;117726.41136794433;97294;101968;102353.48823255984;103748.48125759323;105554;56479.71760141255;56837.43162568322;78534.21465785384;78958.21041789508;80026.59986700029;9032.322575806973;9603.519824008632;9875.802483950138;10425.113865321533;10590.8411373831;52944;55122.17316740172;55724;55947.44052559585;56322.43677563172;70679;70929;88074.11925880659;89081;90157.54921225352;10934.289271197347;11146.164037697366;11519.07847372225;11543.191976561897;11685.649430516758;12940.223586584834;13071.803922940973;14432;14501;14796.7780483295;13289.66775830612;13402.797530827404;13761.449542018207;13941.651458713613;14479.565613031626;13354;13458.596242112751;13521.59435216945;14123.435062597791;14507.782383264048;13480.123791953492;13619.31903404806;13669.863301367257;13890.097143685629;13993.580192594238;10113.595456181965;10881.891181088404;10884.183686223723;10923.344599324066;11064;10943.616973405893;11429.714257143638;11514;11841.940790295994;11941.76116477683 05:52:36 INFO - PROCESS | 16859 | __end_tp_report 05:52:36 INFO - PROCESS | 16859 | __start_cc_report 05:52:36 INFO - PROCESS | 16859 | _x_x_mozilla_cycle_collect,852 05:52:36 INFO - PROCESS | 16859 | __end_cc_report 05:52:36 INFO - PROCESS | 16859 | __startTimestamp1477918356299__endTimestamp 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | ------- Summary: start ------- 05:52:36 INFO - PROCESS | 16859 | Number of tests: 6 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#0] dojo.html Cycles:165 Average:5667.16 Median:5886.44 stddev:643.93 (10.9%) stddev-sans-first:617.08 05:52:36 INFO - PROCESS | 16859 | Values: 3238.7 3250.9 3264.8 3269.3 3303.8 5571.0 5657.9 5660.7 5664.3 5671.7 5718.6 5783.8 5787.5 5792.3 5796.2 5194.7 5207.3 5208.7 5260.4 5275.9 5049.7 5063.3 5277.9 5279.0 5313.8 5154.5 5172.2 5172.8 5175.9 5195.4 5117.6 5122.0 5129.9 5143.7 5200.6 5113.1 5158.4 5192.2 5192.8 5266.9 5791.3 5795.3 5835.5 5847.3 5857.9 5886.6 5951.4 5997.6 6014.9 6191.2 5968.0 5987.2 6008.2 6073.1 6091.0 5687.2 5689.0 5694.4 5698.4 5702.2 5833.2 5984.3 6125.8 6202.0 6288.4 6005.7 6032.9 6037.4 6042.2 6042.3 5861.7 6108.6 6109.8 6118.0 6122.2 6017.3 6062.5 6076.2 6146.0 6165.6 6148.7 6151.8 6163.4 6169.1 6172.0 4490.7 4708.5 4710.0 4714.0 4728.5 4087.5 4124.9 4146.3 4153.0 4196.2 5817.9 5851.6 5856.0 5860.9 5861.6 5986.4 6164.4 6174.3 6187.1 6188.6 5750.6 5805.7 5806.9 5883.2 5900.3 5317.2 5323.3 5324.0 5324.7 5327.9 6158.8 6353.0 6354.2 6355.2 6356.8 6017.2 6044.2 6057.5 6124.8 6131.2 6241.3 6241.4 6285.9 6286.4 6292.5 5888.4 5964.8 5972.6 5985.6 6003.6 6056.9 6087.6 6089.6 6174.5 6175.7 6007.7 6010.6 6019.6 6024.1 6050.5 5831.9 5964.6 5986.0 6031.6 6044.7 5814.2 5842.5 5854.3 5910.5 5923.8 5952.6 5970.0 6032.0 6038.0 6057.4 5668.2 5840.1 5886.4 5891.4 5932.0 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#1] ext.html Cycles:180 Average:68212.31 Median:200117.34 stddev:138306.12 (69.1%) stddev-sans-first:138600.94 05:52:36 INFO - PROCESS | 16859 | Values: 599.6 630.6 644.5 652.1 653.8 33514.0 36631.3 36664.1 45238.8 46361.8 46388.5 46831.5 46884.3 46955.0 46959.0 9118.9 9315.7 9323.1 9330.5 9338.3 8087.9 10876.2 11116.2 11149.1 11480.8 11238.6 11335.6 11342.3 11344.7 11345.9 11127.7 11161.3 11214.4 11235.3 11240.9 11033.2 11086.4 11130.1 11136.1 11152.1 44714.3 45161.5 45164.0 45208.5 45227.3 3675.3 3689.9 3792.8 3950.0 4019.7 33925.7 36514.8 36626.6 36680.1 36819.2 4432.4 4469.0 4479.8 4548.4 4574.9 385069.0 393759.0 394863.0 395455.0 396215.0 70179.0 70478.6 70972.0 71095.0 71178.6 40835.2 41777.0 41797.4 44398.8 44441.3 18657.6 18918.5 18920.0 18921.4 19082.1 13067.2 14458.1 14635.3 14641.0 14644.3 21760.8 22387.1 22647.0 22938.9 23137.7 7881.5 8130.3 8193.9 8388.7 8464.4 1066.0 1101.8 1127.4 1176.2 1202.8 34794.1 35297.0 35322.0 35395.0 35531.5 1118.7 1162.5 1204.4 1213.9 1266.4 17243.6 18112.2 18407.3 18418.2 18566.3 610679.0 614420.0 618612.5 619320.0 620315.0 63524.7 64126.7 64180.7 64242.0 64243.0 1313.8 1411.2 1441.8 1454.6 1458.9 33673.8 36333.3 36491.6 36516.6 36537.3 54197.2 60404.7 62190.7 62478.6 67784.0 524269.0 527084.0 527250.0 529661.0 530018.0 40721.8 45079.2 45155.3 45280.5 49798.0 50946.7 51456.0 51562.5 51580.2 51892.7 43917.8 44137.6 44722.8 45124.3 45240.3 44485.8 45138.5 45358.0 45403.3 45849.7 43976.8 44028.3 44246.3 44470.3 45277.3 30154.8 31946.8 31954.2 32036.2 32137.4 29039.1 29117.4 29658.9 29935.4 30090.0 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#2] jquery.html Cycles:180 Average:28568.43 Median:2115.35 stddev:79264.73 (3747.1%) stddev-sans-first:79458.90 05:52:36 INFO - PROCESS | 16859 | Values: 415.6 431.6 433.3 433.7 444.5 18507.1 19736.4 20184.3 20747.7 20928.2 19033.9 19130.3 19496.5 19705.8 19771.5 5877.4 6058.5 6105.3 6116.9 6128.6 5797.9 5911.6 5918.6 5936.9 5974.1 5903.9 5906.5 5916.6 5919.0 5919.1 5842.7 5876.9 5952.9 5954.3 5955.7 5271.8 5317.7 5332.3 5361.1 5369.5 17136.0 17397.7 18364.9 23152.4 23437.9 2496.3 2509.2 2516.7 2720.3 3225.2 15138.0 15290.3 15410.5 15427.0 15540.4 1985.9 2018.6 2024.6 2049.3 2157.6 54175.5 59449.7 70833.0 73483.0 74748.3 11315.2 11634.8 11640.6 11728.7 11812.1 16611.3 16629.5 16636.9 16654.2 16674.3 8204.6 8233.7 8394.7 8521.2 8584.9 7921.3 7941.4 8112.4 8145.9 8168.4 4958.6 5083.2 5114.3 5125.9 5203.6 2030.5 2073.9 2097.6 2114.9 2115.7 1022.6 1042.6 1048.9 1051.6 1103.1 19831.1 20096.8 20134.3 20323.4 20399.3 1025.5 1045.3 1067.2 1072.2 1094.8 10507.2 11339.5 11583.7 11645.1 11688.1 468867.0 475800.0 481466.0 492916.0 493435.0 85995.0 89709.6 90134.5 90765.5 90776.5 1018.8 1033.5 1046.7 1049.4 1050.8 17441.7 17889.4 18099.7 18198.6 18367.9 41138.6 41381.0 41622.4 42392.4 42477.4 55947.4 56244.0 56662.4 57792.4 58217.0 8365.4 8961.1 9038.0 9112.9 9296.5 12671.9 12840.9 12859.6 13571.9 13816.8 10620.7 11218.7 11274.4 11297.4 11699.5 11156.7 11635.0 12015.2 12063.2 12194.2 11619.7 11694.6 11709.8 11750.5 11947.2 9005.3 9173.9 9216.9 9230.9 9395.4 11418.6 11434.8 11486.9 11523.5 11741.6 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#3] mootools.html Cycles:180 Average:6350.12 Median:4597.93 stddev:3732.50 (81.2%) stddev-sans-first:3724.18 05:52:36 INFO - PROCESS | 16859 | Values: 1366.8 1513.3 1531.4 1690.7 1855.1 8262.2 9322.9 9536.2 9568.9 9929.5 9696.5 9736.7 9759.7 9777.0 9782.6 2472.8 2513.6 2518.9 2522.1 2523.0 2439.0 2484.7 2490.6 2496.1 2498.8 2462.3 2476.5 2523.4 2524.8 2527.5 2408.7 2430.2 2432.6 2433.8 2453.5 2235.2 2264.5 2267.6 2268.3 2306.6 9564.1 9746.8 9841.9 9856.0 9895.5 5537.3 5737.9 5744.7 5755.2 5779.7 3460.1 3493.2 3508.6 3553.8 3555.0 1144.6 1150.7 1153.8 1177.4 1183.0 13571.6 13609.2 13652.4 13699.0 13761.1 4466.2 4650.4 4726.0 4728.8 4762.3 10832.2 10852.2 10857.1 10858.2 10861.3 5537.5 5556.7 5565.2 5587.1 5608.3 4449.2 4601.6 4870.2 5109.4 5166.0 4062.4 4114.3 4150.6 4152.1 4221.7 1194.5 1200.0 1203.7 1204.0 1209.6 954.7 1071.5 1073.0 1074.6 1091.2 6476.8 6531.8 6572.0 6718.1 6725.5 448.4 449.9 457.6 472.7 476.5 4558.3 4594.3 4653.8 4704.2 4792.2 12105.1 12107.4 12382.8 12401.3 12600.7 12438.6 12446.1 12450.7 12453.5 12463.6 8744.3 8951.7 9022.3 9274.3 9311.8 6621.3 6763.9 6789.3 6835.3 6922.8 11089.1 11102.9 11354.0 11487.1 11527.5 10335.9 10647.5 11271.9 11561.5 11640.5 8468.7 8636.3 8684.7 8821.0 9008.9 9564.7 9693.1 9718.7 9723.8 9728.1 8200.0 8277.9 8355.3 8414.3 8516.3 8293.0 8307.5 8349.2 8354.9 8454.8 7729.7 7755.5 7781.5 7818.3 7881.6 5854.1 5896.9 5908.3 5923.3 6007.9 5952.8 5953.5 6040.3 6066.2 6082.8 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#4] prototype.html Cycles:180 Average:4762.46 Median:5276.37 stddev:1360.15 (25.8%) stddev-sans-first:1323.05 05:52:36 INFO - PROCESS | 16859 | Values: 351.0 362.3 363.9 367.5 370.3 5483.8 5533.2 5585.8 5589.4 5594.5 5317.6 5394.0 5430.1 5506.2 5508.3 4318.2 4352.0 4354.3 4379.3 4410.3 4371.2 4382.8 4385.4 4391.0 4421.7 4244.6 4337.7 4364.6 4366.9 4387.0 4310.6 4324.0 4325.6 4339.8 4360.9 3888.8 3890.7 3899.3 3905.8 3920.4 5407.5 5423.3 5451.0 5451.6 5464.8 4842.7 4975.7 4991.9 4995.6 5021.8 5706.2 5710.9 5716.4 5716.9 5722.9 4265.4 4267.4 4307.6 4319.7 4328.6 5760.7 5816.7 5821.7 6082.1 6091.0 4323.0 4351.1 4380.4 4380.8 4383.5 4427.9 4432.3 4434.1 4436.4 4441.8 4879.3 5018.8 5019.2 5028.3 5042.9 5872.7 5878.8 5881.2 5937.6 5944.1 1505.3 1510.8 1526.6 1529.3 1530.8 944.6 975.9 980.5 982.7 1003.8 4973.7 4985.1 5055.2 5069.6 5090.5 5235.7 5273.5 5301.7 5303.4 5349.3 4900.6 5028.0 5045.4 5049.2 5053.8 2771.7 2813.4 2814.8 2832.5 2833.2 5375.7 5422.2 5437.9 5440.6 5508.8 5114.7 5248.7 5340.9 5371.9 5377.7 5404.9 5439.7 5445.9 5449.4 5469.6 4921.8 5261.3 5279.3 5300.2 5331.7 5165.6 5184.8 5201.5 5235.0 5285.7 5502.3 5990.8 6063.2 6064.5 6184.6 4311.8 4319.2 4331.7 4348.1 4358.7 5802.6 6004.4 6046.8 6139.1 6143.7 5855.0 5860.2 5864.4 5870.6 5887.7 5898.5 5901.8 5905.5 5906.6 5924.8 5665.6 5770.4 5802.1 5819.2 5819.4 5901.9 5922.5 5925.0 5942.1 5952.3 5471.6 5707.7 5711.8 5750.3 5763.7 05:52:36 INFO - PROCESS | 16859 | 05:52:36 INFO - PROCESS | 16859 | [#5] yui.html Cycles:180 Average:20260.93 Median:4862.02 stddev:28630.48 (588.9%) stddev-sans-first:28673.41 05:52:36 INFO - PROCESS | 16859 | Values: 775.2 787.3 798.4 822.3 843.0 14620.3 15613.1 15658.0 15689.1 15713.4 14031.5 14077.5 14110.2 14137.0 14145.2 694.8 865.7 873.0 885.2 893.6 851.1 851.7 852.7 854.4 854.5 923.4 924.2 925.7 926.5 926.8 919.7 920.3 921.0 921.0 924.1 674.0 679.7 680.4 681.4 682.6 13612.0 13612.9 13623.0 13643.9 13653.9 14860.3 15118.8 15150.0 15173.1 15293.5 11319.9 11352.2 11469.1 11665.0 11790.4 3993.5 4004.5 4021.2 4166.7 4194.3 82492.0 86801.1 87694.0 88247.6 88862.6 11611.1 11662.7 11761.5 11819.6 11852.1 21728.1 21736.9 21944.2 21987.2 22002.3 8580.5 8645.5 8739.2 8743.6 9044.5 5201.2 5207.3 5213.6 5349.4 5369.1 3566.3 3707.9 3710.5 3726.3 3739.8 638.6 642.4 650.1 650.3 659.9 632.3 645.3 705.6 706.9 731.2 9285.3 9451.2 9624.0 9872.2 9954.2 630.3 634.5 718.9 728.5 754.1 4608.2 4637.9 4815.2 4908.9 5018.6 69687.0 69923.3 69999.3 111067.0 117726.4 97294.0 101968.0 102353.5 103748.5 105554.0 56479.7 56837.4 78534.2 78958.2 80026.6 9032.3 9603.5 9875.8 10425.1 10590.8 52944.0 55122.2 55724.0 55947.4 56322.4 70679.0 70929.0 88074.1 89081.0 90157.5 10934.3 11146.2 11519.1 11543.2 11685.6 12940.2 13071.8 14432.0 14501.0 14796.8 13289.7 13402.8 13761.4 13941.7 14479.6 13354.0 13458.6 13521.6 14123.4 14507.8 13480.1 13619.3 13669.9 13890.1 13993.6 10113.6 10881.9 10884.2 10923.3 11064.0 10943.6 11429.7 11514.0 11841.9 11941.8 05:52:36 INFO - PROCESS | 16859 | -------- Summary: end -------- 05:52:36 INFO - PROCESS | 16859 | 05:52:37 INFO - TEST-INFO | 16859: exit 0 05:52:37 INFO - TEST-OK | dromaeo_css | took 1112800ms 05:52:37 INFO - TEST-START | kraken 05:52:37 INFO - operating with platform_type : linux_ 05:52:37 INFO - Initialising browser for kraken test... 05:52:37 INFO - TEST-INFO | started process 17048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx2rkjx/profile http://localhost:39763/getInfo.html) 05:52:40 INFO - PROCESS | 17048 | __metrics Screen width/height:1600/1200 05:52:40 INFO - PROCESS | 17048 | colorDepth:24 05:52:40 INFO - PROCESS | 17048 | Browser inner width/height: 1024/697 05:52:40 INFO - PROCESS | 17048 | __metrics 05:52:41 INFO - PROCESS | 17048 | 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:52:41 INFO - PROCESS | 17048 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:42 INFO - TEST-INFO | 17048: exit 0 05:52:42 INFO - Browser initialized. 05:52:42 INFO - Running cycle 1/1 for kraken test... 05:52:42 INFO - TEST-INFO | started process 17135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx2rkjx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:54 INFO - PROCESS | 17135 | RSS: Main: 144297984 05:52:54 INFO - PROCESS | 17135 | 05:52:56 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39763/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:57 INFO - PROCESS | 17135 | RSS: Main: 199077888 05:52:57 INFO - PROCESS | 17135 | 05:53:00 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39763/tests/kraken/driver.html?testName=audio-dft) 05:53:00 INFO - PROCESS | 17135 | RSS: Main: 184422400 05:53:00 INFO - PROCESS | 17135 | 05:53:03 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39763/tests/kraken/driver.html?testName=audio-fft) 05:53:04 INFO - PROCESS | 17135 | RSS: Main: 274763776 05:53:04 INFO - PROCESS | 17135 | 05:53:06 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39763/tests/kraken/driver.html?testName=audio-oscillator) 05:53:07 INFO - PROCESS | 17135 | RSS: Main: 181051392 05:53:07 INFO - PROCESS | 17135 | 05:53:09 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39763/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:53:09 INFO - PROCESS | 17135 | RSS: Main: 180207616 05:53:09 INFO - PROCESS | 17135 | 05:53:12 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39763/tests/kraken/driver.html?testName=imaging-darkroom) 05:53:13 INFO - PROCESS | 17135 | RSS: Main: 215310336 05:53:13 INFO - PROCESS | 17135 | 05:53:16 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39763/tests/kraken/driver.html?testName=imaging-desaturate) 05:53:17 INFO - PROCESS | 17135 | RSS: Main: 213921792 05:53:17 INFO - PROCESS | 17135 | 05:53:20 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39763/tests/kraken/driver.html?testName=json-parse-financial) 05:53:20 INFO - PROCESS | 17135 | RSS: Main: 213946368 05:53:20 INFO - PROCESS | 17135 | 05:53:22 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39763/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:53:22 INFO - PROCESS | 17135 | RSS: Main: 176005120 05:53:22 INFO - PROCESS | 17135 | 05:53:24 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:53:25 INFO - PROCESS | 17135 | RSS: Main: 183463936 05:53:25 INFO - PROCESS | 17135 | 05:53:27 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:53:27 INFO - PROCESS | 17135 | RSS: Main: 189956096 05:53:27 INFO - PROCESS | 17135 | 05:53:30 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:30 INFO - PROCESS | 17135 | RSS: Main: 189894656 05:53:30 INFO - PROCESS | 17135 | 05:53:33 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:34 INFO - PROCESS | 17135 | RSS: Main: 192995328 05:53:34 INFO - PROCESS | 17135 | 05:53:35 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:39763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39763/tests/kraken/driver.html?testName=ai-astar) 05:53:36 INFO - PROCESS | 17135 | RSS: Main: 242941952 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | __start_tp_report 05:53:36 INFO - PROCESS | 17135 | _x_x_mozilla_page_load 05:53:36 INFO - PROCESS | 17135 | _x_x_mozilla_page_load_details 05:53:36 INFO - PROCESS | 17135 | |i|pagename|runs| 05:53:36 INFO - PROCESS | 17135 | |0;ai-astar;111;110;118;109;109;110;109;109;109;109 05:53:36 INFO - PROCESS | 17135 | |1;audio-beat-detection;150;121;144;173;122;148;144;117;176;115 05:53:36 INFO - PROCESS | 17135 | |2;audio-dft;161;159;160;161;160;159;159;159;160;160 05:53:36 INFO - PROCESS | 17135 | |3;audio-fft;77;75;75;184;76;73;74;75;176;75 05:53:36 INFO - PROCESS | 17135 | |4;audio-oscillator;85;85;153;84;85;85;149;84;84;138 05:53:36 INFO - PROCESS | 17135 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;116;117;117 05:53:36 INFO - PROCESS | 17135 | |6;imaging-darkroom;146;147;146;146;147;148;147;146;146;147 05:53:36 INFO - PROCESS | 17135 | |7;imaging-desaturate;95;95;96;94;95;95;95;95;94;95 05:53:36 INFO - PROCESS | 17135 | |8;json-parse-financial;49;50;52;49;49;50;53;58;51;49 05:53:36 INFO - PROCESS | 17135 | |9;json-stringify-tinderbox;61;62;63;62;61;61;60;61;62;62 05:53:36 INFO - PROCESS | 17135 | |10;stanford-crypto-aes;82;79;81;72;76;78;73;75;73;73 05:53:36 INFO - PROCESS | 17135 | |11;stanford-crypto-ccm;133;126;128;128;131;116;123;120;159;117 05:53:36 INFO - PROCESS | 17135 | |12;stanford-crypto-pbkdf2;158;159;154;161;155;157;156;154;153;154 05:53:36 INFO - PROCESS | 17135 | |13;stanford-crypto-sha256-iterative;64;68;65;66;64;65;63;63;61;65 05:53:36 INFO - PROCESS | 17135 | __end_tp_report 05:53:36 INFO - PROCESS | 17135 | __start_cc_report 05:53:36 INFO - PROCESS | 17135 | _x_x_mozilla_cycle_collect,1661 05:53:36 INFO - PROCESS | 17135 | __end_cc_report 05:53:36 INFO - PROCESS | 17135 | __startTimestamp1477918416025__endTimestamp 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | ------- Summary: start ------- 05:53:36 INFO - PROCESS | 17135 | Number of tests: 14 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#0] ai-astar Cycles:10 Average:110.30 Median:109.50 stddev:2.79 (2.5%) stddev-sans-first:2.95 05:53:36 INFO - PROCESS | 17135 | Values: 111.0 110.0 118.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:146.00 stddev:22.14 (15.2%) stddev-sans-first:23.24 05:53:36 INFO - PROCESS | 17135 | Values: 150.0 121.0 144.0 173.0 122.0 148.0 144.0 117.0 176.0 115.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 05:53:36 INFO - PROCESS | 17135 | Values: 161.0 159.0 160.0 161.0 160.0 159.0 159.0 159.0 160.0 160.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#3] audio-fft Cycles:10 Average:96.00 Median:75.00 stddev:44.32 (59.1%) stddev-sans-first:46.48 05:53:36 INFO - PROCESS | 17135 | Values: 77.0 75.0 75.0 184.0 76.0 73.0 74.0 75.0 176.0 75.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:84.50 stddev:30.22 (35.8%) stddev-sans-first:31.33 05:53:36 INFO - PROCESS | 17135 | Values: 85.0 85.0 153.0 84.0 85.0 85.0 149.0 84.0 84.0 138.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:53:36 INFO - PROCESS | 17135 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 05:53:36 INFO - PROCESS | 17135 | Values: 146.0 147.0 146.0 146.0 147.0 148.0 147.0 146.0 146.0 147.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:53:36 INFO - PROCESS | 17135 | Values: 95.0 95.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:50.50 stddev:2.83 (5.6%) stddev-sans-first:2.91 05:53:36 INFO - PROCESS | 17135 | Values: 49.0 50.0 52.0 49.0 49.0 50.0 53.0 58.0 51.0 49.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 05:53:36 INFO - PROCESS | 17135 | Values: 61.0 62.0 63.0 62.0 61.0 61.0 60.0 61.0 62.0 62.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:77.00 stddev:3.61 (4.7%) stddev-sans-first:3.17 05:53:36 INFO - PROCESS | 17135 | Values: 82.0 79.0 81.0 72.0 76.0 78.0 73.0 75.0 73.0 73.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:128.00 stddev:12.26 (9.6%) stddev-sans-first:12.88 05:53:36 INFO - PROCESS | 17135 | Values: 133.0 126.0 128.0 128.0 131.0 116.0 123.0 120.0 159.0 117.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.10 Median:156.50 stddev:2.60 (1.7%) stddev-sans-first:2.67 05:53:36 INFO - PROCESS | 17135 | Values: 158.0 159.0 154.0 161.0 155.0 157.0 156.0 154.0 153.0 154.0 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - PROCESS | 17135 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.90 (2.9%) stddev-sans-first:2.01 05:53:36 INFO - PROCESS | 17135 | Values: 64.0 68.0 65.0 66.0 64.0 65.0 63.0 63.0 61.0 65.0 05:53:36 INFO - PROCESS | 17135 | -------- Summary: end -------- 05:53:36 INFO - PROCESS | 17135 | 05:53:36 INFO - TEST-INFO | 17135: exit 0 05:53:37 INFO - TEST-OK | kraken | took 59950ms 05:53:37 INFO - SUITE-END | took 1172s 05:53:37 INFO - Completed test suite (00:19:32) 05:53:37 INFO - javascript benchmark 05:53:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3238.6761323867613, 3250.918727031824, 3264.7551433642484, 3269.313444176723, 3303.785253958494, 5570.972145139273, 5657.915131273029, 5660.688662123582, 5664.348599911006, 5671.6880571568545, 5718.571107166958, 5783.826485205451, 5787.47912687858, 5792.333881603617, 5796.217510636063, 5194.71429071401, 5207.3490813648295, 5208.713520756357, 5260.421353651095, 5275.86810329741, 5049.697018178903, 5063.341765570471, 5277.86805329866, 5278.996990571798, 5313.787448502056, 5154.536091751742, 5172.198309262051, 5172.767225474844, 5175.922361164567, 5195.350581177353, 5117.5650069744115, 5122.0, 5129.9230511542355, 5143.691378517301, 5200.609954253446, 5113.105206588869, 5158.432572417031, 5192.221166824968, 5192.818251361185, 5266.894662106774, 5791.276090488689, 5795.3045634523705, 5835.533157347402, 5847.327557330919, 5857.912131318034, 5886.617369870987, 5951.4048595140575, 5997.640141591519, 6014.909776353358, 6191.195144631214, 5968.045112781934, 5987.161797348374, 6008.24896887889, 6073.089036644495, 6091.025435930274, 5687.175359572839, 5689.0328644130595, 5694.402087780765, 5698.401667824902, 5702.201691763156, 5833.241678581757, 5984.251968503937, 6125.815438131368, 6201.976673848854, 6288.371162883766, 6005.6997150142315, 6032.939670603238, 6037.396260374016, 6042.154098426224, 6042.274927008787, 5861.70691465425, 6108.602940808833, 6109.816705498843, 6118.0, 6122.234720659918, 6017.2779266488305, 6062.514998800086, 6076.240469941257, 6145.9692701536205, 6165.599236049642, 6148.661823599745, 6151.753929842756, 6163.3528479509905, 6169.105479705508, 6172.043333283348, 4490.730556166641, 4708.458527269339, 4709.95290047102, 4714.0, 4728.4798672146035, 4087.529934057562, 4124.8556300529335, 4146.274401979642, 4152.958470415318, 4196.181744559843, 5817.854553636108, 5851.61964472308, 5856.033754430557, 5860.8534786629825, 5861.589688721749, 5986.4013598639785, 6164.3835616438, 6174.25908890936, 6187.133801267826, 6188.628682279078, 5750.6262092963825, 5805.6806875621405, 5806.912896306559, 5883.17635531026, 5900.291964964232, 5317.202419637086, 5323.30796996388, 5323.973380133075, 5324.680519168863, 5327.946720532823, 6158.815235542851, 6352.968235158887, 6354.237491500957, 6355.173827402408, 6356.77751278703, 6017.157597936205, 6044.153818465506, 6057.545684073624, 6124.808163753463, 6131.162586540493, 6241.344658810759, 6241.407066328691, 6285.937140628718, 6286.4342209201395, 6292.528060395397, 5888.411158884077, 5964.7912323068485, 5972.598652311108, 5985.581009329305, 6003.609765365237, 6056.878862422815, 6087.604305720114, 6089.604175728564, 6174.536909731698, 6175.722092505938, 6007.729652165751, 6010.609310394811, 6019.638821670714, 6024.126501657148, 6050.485708714808, 5831.854203644943, 5964.642121472725, 5986.0, 6031.577789554689, 6044.667543285162, 5814.156947242711, 5842.503387211965, 5854.268216472941, 5910.52715782745, 5923.822285331447, 5952.642841429528, 5969.970150149222, 6031.969840150771, 6038.0, 6057.363976782551, 5668.234788303526, 5840.094785308284, 5886.440788125121, 5891.410858914074, 5931.970340148272], "value": 5623.28044180319, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.5892813422803, 630.593267342564, 644.4715333426586, 652.0675434129196, 653.8103949854542, 33514.0, 36631.267374652525, 36664.083397915034, 45238.77380613092, 46361.768191159, 46388.536114638846, 46831.53168468314, 46884.29673554891, 46955.0, 46959.0, 9118.90881091189, 9315.673951411702, 9323.114304141096, 9330.486823224719, 9338.346315757899, 8087.878681819778, 10876.184286178528, 11116.221864469491, 11149.108071354272, 11480.827787583194, 11238.550457981672, 11335.603253886116, 11342.262752921033, 11344.716382090432, 11345.886541134607, 11127.666170014912, 11161.274517156358, 11214.383208923506, 11235.269707468988, 11240.943795281053, 11033.227674062819, 11086.447897294385, 11130.109591232682, 11136.109111271078, 11152.052075573505, 44714.32928506075, 45161.54838451639, 45164.0, 45208.547914520765, 45227.32159017617, 3675.3016926783826, 3689.8893033209047, 3792.829322680486, 3950.032242100675, 4019.678425725935, 33925.660743392495, 36514.81742591297, 36626.63373366259, 36680.08299792527, 36819.23855710832, 4432.423784907941, 4469.0, 4479.819328276927, 4548.431446069241, 4574.862754117348, 385069.0, 393759.0, 394863.0, 395455.0, 396215.0, 70179.0, 70478.64760676163, 70972.0, 71095.0, 71178.64410677913, 40835.1832963345, 41777.0, 41797.37303940443, 44398.778006109766, 44441.33337999933, 18657.626847462976, 18918.52703682419, 18920.0, 18921.432357029313, 19082.14130364137, 13067.150635208682, 14458.132512049311, 14635.341409636592, 14641.048331858396, 14644.267786610626, 21760.782392176192, 22387.104515819512, 22647.0, 22938.885305573367, 23137.652935205984, 7881.487703299267, 8130.349572034342, 8193.862099639762, 8388.706395276136, 8464.444933128101, 1065.9680209593726, 1101.7906597746403, 1127.402446851387, 1176.1590462819127, 1202.7834989701937, 34794.13014674653, 35297.0, 35322.0, 35395.0, 35531.4670279946, 1118.7370967822717, 1162.54079638544, 1204.4098391787961, 1213.8664746877837, 1266.4301064520819, 17243.568910777332, 18112.184951677205, 18407.26370945174, 18418.17118229683, 18566.25734970613, 610679.0, 614420.0, 618612.5346866364, 619320.0, 620315.0, 63524.68237658782, 64126.6793666038, 64180.67909660422, 64242.0, 64243.0, 1313.7897936330332, 1411.1533080151826, 1441.84139744628, 1454.6363409147714, 1458.8687018168416, 33673.83163084169, 36333.273334532634, 36491.63508364883, 36516.63483365239, 36537.26925461529, 54197.18704219361, 60404.69797650984, 62190.68904655448, 62478.56367899809, 67784.0, 524269.0, 527084.0, 527250.0, 529661.0, 530018.0, 40721.79639101904, 45079.191698525916, 45155.32267016063, 45280.54719452763, 49798.0, 50946.74526627343, 51456.0, 51562.48437515727, 51580.22629660633, 51892.740536297075, 43917.78041109774, 44137.55862441463, 44722.77638611786, 45124.32313515365, 45240.32139517976, 44485.77757111194, 45138.54861451475, 45358.0, 45403.318950215114, 45849.66475000492, 43976.78011609922, 44028.33957490704, 44246.336304956094, 44470.3329450065, 45277.32084018677, 30154.84922575373, 31946.84026579852, 31954.20114497156, 32036.19909502188, 32137.357252854345, 29039.088595515026, 29117.417651647273, 29658.851705741334, 29935.4012919736, 30090.0], "value": 22212.01748808612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.58856731835493, 431.5950994958228, 433.3305043707472, 433.72458488859564, 444.47773865707785, 18507.074646267683, 19736.407907762754, 20184.293549725764, 20747.688784668204, 20928.162873485082, 19033.859413196842, 19130.33043843466, 19496.512587185327, 19705.802941970574, 19771.505712357197, 5877.382874798138, 6058.515318774509, 6105.297890742577, 6116.938830611703, 6128.601640893327, 5797.913031304533, 5911.586188966774, 5918.615290006157, 5936.940630593704, 5974.103884417351, 5903.940960590382, 5906.497947674453, 5916.585838991272, 5919.0, 5919.112133180014, 5842.678652674102, 5876.91184632233, 5952.851178720523, 5954.345022047614, 5955.672438015908, 5271.789128434858, 5317.654352467077, 5332.33345831771, 5361.08861493547, 5369.48989845964, 17136.0, 17397.652046959116, 18364.90817545917, 23152.42118947023, 23437.88281058601, 2496.3010357100043, 2509.2346834215555, 2516.7357427470033, 2720.319920019995, 3225.177579717157, 15138.0, 15290.311935962909, 15410.460633877872, 15427.0, 15540.378384864705, 1985.9007049647605, 2018.5559176981042, 2024.5748392837522, 2049.2929939170963, 2157.6439887418555, 54175.45824541783, 59449.702751485966, 70833.0, 73483.0, 74748.25251747413, 11315.20793544444, 11634.76730465403, 11640.592579259684, 11728.706782330339, 11812.114091443176, 16611.252493637814, 16629.50111496657, 16636.916815415847, 16654.16729163561, 16674.33302667904, 8204.58977051157, 8233.670653173926, 8394.664213431517, 8521.233089022018, 8584.91415085841, 7921.326687231534, 7941.36469082472, 8112.391570632226, 8145.877811832827, 8168.387370947203, 4958.553730164303, 5083.161278389024, 5114.3095682083185, 5125.923111153335, 5203.557697595671, 2030.4923769057737, 2073.9377818665466, 2097.559512502361, 2114.9471263218234, 2115.746110466723, 1022.5602990714065, 1042.5621239079605, 1048.8846226915034, 1051.6476980211562, 1103.1285284625217, 19831.107600158026, 20096.799032009785, 20134.29529966444, 20323.390298291073, 20399.28602498935, 1025.4565080507193, 1045.273534893242, 1067.236925598201, 1072.2226385870338, 1094.8357746337954, 10507.159427245802, 11339.546418143182, 11583.6524904253, 11645.068394528418, 11688.123390745728, 468867.0, 475800.0, 481466.0, 492916.0, 493435.0, 85995.0, 89709.55145224363, 90134.54932725294, 90765.54617227004, 90776.546117269, 1018.7809620931536, 1033.4625994482826, 1046.7233766557706, 1049.4280617063719, 1050.7845891592206, 17441.651166976844, 17889.37387191442, 18099.728504072187, 18198.636027279645, 18367.908160459112, 41138.588614113476, 41381.0, 41622.37566436567, 42392.36411453893, 42477.36283955805, 55947.440525594226, 56244.0, 56662.433375665714, 57792.422075778704, 58217.0, 8365.372597055368, 8961.103889611248, 9037.954810225907, 9112.908870911206, 9296.488693121943, 12671.936640316739, 12840.935795320964, 12859.614211573667, 13571.932140339235, 13816.792748108586, 10620.681379558626, 11218.719532011439, 11274.37990910475, 11297.43512824372, 11699.473523691624, 11156.665300041012, 11635.0, 12015.158938874545, 12063.155579109378, 12194.207376520499, 11619.6514104577, 11694.649160525198, 11709.765804683688, 11750.529978800752, 11947.223430476992, 9005.324600655109, 9173.862392064259, 9216.861747073664, 9230.907690923004, 9395.389299695498, 11418.600348988079, 11434.82847757301, 11486.885131148916, 11523.53905843757, 11741.589044383747], "value": 9516.92682405721, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.8428130764964, 1513.2736286582438, 1531.3950989359203, 1690.7041267778143, 1855.1280897977945, 8262.215089566489, 9322.906770932288, 9536.237101031918, 9568.904310956897, 9929.453880036595, 9696.466694331826, 9736.70789876303, 9759.658411955583, 9777.0, 9782.559784809682, 2472.7774500295013, 2513.6229565565127, 2518.899244030237, 2522.092046863128, 2523.0, 2439.0, 2484.7391023942496, 2490.5890528062846, 2496.0764517128687, 2498.775110240078, 2462.2982450001755, 2476.5418397596504, 2523.40699935515, 2524.823262371635, 2527.494501099778, 2408.7350391456926, 2430.173740928084, 2432.6351047342864, 2433.7687919647606, 2453.4970331082086, 2235.1953296813135, 2264.4565304327007, 2267.6258417361114, 2268.3421807675754, 2306.630939049744, 9564.091411315914, 9746.756331091708, 9841.90158098417, 9856.0, 9895.455749933752, 5537.252470916415, 5737.856553586152, 5744.6840423776675, 5755.194272801811, 5779.711014449261, 3460.1349662584353, 3493.179102910814, 3508.578970523528, 3553.7690050146657, 3555.0401391624373, 1144.6165534545937, 1150.7468356961456, 1153.8326942593278, 1177.4289469607234, 1182.9885447942058, 13571.59285221445, 13609.183448993092, 13652.385642646006, 13699.041067125305, 13761.105528140639, 4466.240739074366, 4650.372199753057, 4726.0, 4728.770519664912, 4762.333273341735, 10832.241743078066, 10852.18608604342, 10857.131429485622, 10858.239923205458, 10861.348319100722, 5537.473939975695, 5556.749946252347, 5565.220869078333, 5587.078132108157, 5608.298962629671, 4449.1991441540185, 4601.585857272861, 4870.197958477019, 5109.3613298337705, 5165.974170129125, 4062.370332598452, 4114.259433301974, 4150.6471949884, 4152.148809494107, 4221.746695198237, 1194.50428072349, 1199.9820002699967, 1203.7111093337535, 1204.0307552420386, 1209.625016244967, 954.7279025477706, 1071.49104175516, 1073.039629531565, 1074.6238816414193, 1091.1925175370168, 6476.838079048061, 6531.804045878631, 6571.967140164268, 6718.093057437183, 6725.529212955046, 448.4439295273837, 449.910017996402, 457.599600349694, 472.6502388232683, 476.5020553521509, 4558.270676691714, 4594.264917613166, 4653.790579423934, 4704.1767690654, 4792.161371759928, 12105.092118091003, 12107.39463026845, 12382.81425778614, 12401.317927514074, 12600.684982875502, 12438.626841194779, 12446.128770986124, 12450.688732781753, 12453.501859925682, 12463.563775268003, 8744.344174186961, 8951.686690965915, 9022.323325750594, 9274.30442716799, 9311.813763724553, 6621.3047629998155, 6763.898541521783, 6789.253182149861, 6835.316468353125, 6922.7923162305215, 11089.112870970464, 11102.944485277521, 11354.0, 11487.081033517466, 11527.481263343001, 10335.94832025835, 10647.520861561403, 11271.887281127083, 11561.479733412185, 11640.476178572155, 8468.66125354979, 8636.309095272238, 8684.652613895627, 8820.955895220482, 9008.86486702713, 9564.66523671668, 9693.079157480028, 9718.70843874685, 9723.756906077404, 9728.124468797841, 8199.95900020496, 8277.875831862648, 8355.289800366794, 8414.3268538518, 8516.318694504302, 8293.0, 8307.501549907025, 8349.165083491602, 8354.916450835413, 8454.788630284293, 7729.690812367442, 7755.457118001911, 7781.5331080135375, 7818.3354414875375, 7881.566513841564, 5854.063349864001, 5896.9115463268945, 5908.3205431375945, 5923.348431672599, 6007.94290662598, 5952.821415357546, 5953.464188223081, 6040.305364883095, 6066.181065556093, 6082.847928801815], "value": 4911.562969557651, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.9947350789737, 362.2826802930197, 363.8617325416342, 367.481850590667, 370.279805777762, 5483.8080667176655, 5533.170024496322, 5585.832425027255, 5589.385167631557, 5594.49649531542, 5317.627766056377, 5393.973030134854, 5430.149171684868, 5506.201600767887, 5508.3114610673665, 4318.157959197958, 4351.978240108795, 4354.259775838099, 4379.2848433970985, 4410.338449232609, 4371.213181627306, 4382.824687012516, 4385.386045953569, 4391.0, 4421.712588681741, 4244.617984381405, 4337.718048326865, 4364.585364390377, 4366.934495982563, 4387.0, 4310.56894310568, 4323.978380108079, 4325.589069038437, 4339.849939765964, 4360.931571764938, 3888.844446222148, 3890.735510958921, 3899.317619416619, 3905.8437662493466, 3920.372740361557, 5407.486288802557, 5423.2949716537, 5451.04606693827, 5451.64564303319, 5464.781408743645, 4842.733649649268, 4975.676581022222, 4991.850244492671, 4995.5753760930365, 5021.774020169101, 5706.174024312222, 5710.942890571083, 5716.428357164292, 5716.8856622867725, 5722.914156287659, 4265.38151967966, 4267.445232119804, 4307.612314891675, 4319.654427645781, 4328.632066274339, 5760.683162426065, 5816.680082595413, 5821.708914554255, 6082.1180928765525, 6091.0, 4323.0, 4351.06452112791, 4380.4305440292555, 4380.759058251827, 4383.473983121982, 4427.889302767457, 4432.312991486325, 4434.135343607965, 4436.401085853433, 4441.7779111044965, 4879.2925025871045, 5018.824341148115, 5019.247112933089, 5028.270900719448, 5042.924356134661, 5872.677002764889, 5878.794242201502, 5881.176635271066, 5937.584369094122, 5944.117103204942, 1505.33012809301, 1510.7884896114554, 1526.5725596832904, 1529.258309719785, 1530.8163020437396, 944.617429940875, 975.8536219567121, 980.475360813927, 982.7444864335181, 1003.82934901067, 4973.726445045557, 4985.108636990537, 5055.191169412877, 5069.619778516626, 5090.49095090488, 5235.65968212065, 5273.472652734696, 5301.655392399482, 5303.443138470482, 5349.251104845324, 4900.583450406755, 5027.974860125749, 5045.445001049881, 5049.192129259301, 5053.772580233898, 2771.722827717212, 2813.3529288263403, 2814.8029637925556, 2832.5326321156776, 2833.2208642623445, 5375.704336261543, 5422.240886275925, 5437.918431223535, 5440.591955603346, 5508.807191748269, 5114.718690471986, 5248.737563121828, 5340.866478338073, 5371.892562148813, 5377.7311134443125, 5404.918926216189, 5439.700816455053, 5445.918311225335, 5449.41966829615, 5469.644473109235, 4921.753912304299, 5261.316728468585, 5279.260903473593, 5300.197932702899, 5331.680099194061, 5165.586753059822, 5184.7926082956255, 5201.505856943584, 5234.97382513085, 5285.656432331886, 5502.339719233718, 5990.820275391745, 6063.181470501426, 6064.4535643410645, 6184.567080304336, 4311.762853042987, 4319.235689159003, 4331.660903112284, 4348.130373925291, 4358.716683415568, 5802.593818432838, 6004.369541198112, 6046.848828779316, 6139.079138129317, 6143.692815359124, 5855.0, 5860.208871802252, 5864.445921830804, 5870.647761134345, 5887.676177810262, 5898.528117750656, 5901.793437229677, 5905.527557795452, 5906.645601263938, 5924.822255332347, 5665.575081868794, 5770.394108618702, 5802.056311135817, 5819.243498345188, 5819.410362388866, 5901.852453688693, 5922.526197904243, 5925.0, 5942.12822960766, 5952.315483719428, 5471.616986811045, 5707.6575405475805, 5711.82907503969, 5750.281214848144, 5763.740631671669], "value": 4336.38128037109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.1937984496125, 787.2953706432743, 798.4011991006745, 822.3051521464361, 842.9704960326388, 14620.34208460619, 15613.063216207007, 15658.0, 15689.058656480605, 15713.371465141396, 14031.5088971886, 14077.50728724495, 14110.153390796533, 14137.0, 14145.151290922551, 694.8054544727485, 865.671045002898, 872.97817554561, 885.1900511032397, 893.5755516129819, 851.0808327006818, 851.7189327521903, 852.6802449081595, 854.363499193099, 854.5214679779343, 923.4182465047011, 924.2098006204666, 925.7361651929201, 926.4904302633558, 926.847070233414, 919.7102912582515, 920.3419555018161, 920.9631614735403, 921.0099143420816, 924.075924075924, 674.0193019157115, 679.7281087564965, 680.4352387518397, 681.3595220492721, 682.6313790553095, 13612.0, 13612.931935340359, 13623.0, 13643.931780341134, 13653.86346136536, 14860.331285092198, 15118.848811511854, 15150.0, 15173.089614623048, 15293.541193764206, 11319.886801131966, 11352.205345625809, 11469.139814513945, 11665.0, 11790.35153066581, 3993.520777506718, 4004.5194576650697, 4021.195760847842, 4166.729162604421, 4194.3498757692605, 82492.0, 86801.13198867993, 87694.0, 88247.55876220578, 88862.5556872218, 11611.129165312635, 11662.708432289091, 11761.529538818522, 11819.645410637522, 11852.11109166816, 21728.130874764833, 21736.891315543322, 21944.231951881928, 21987.23044693428, 22002.339929802132, 8580.528070956032, 8645.481271123752, 8739.21347078766, 8743.606537705818, 9044.502552359552, 5201.167813149954, 5207.3490813648295, 5213.6089793265655, 5349.358077030781, 5369.060414427538, 3566.268914872445, 3707.925841483155, 3710.461983012448, 3726.273376691519, 3739.794311312904, 638.6391688695873, 642.4250295985067, 650.0963660511892, 650.3334082565314, 659.9144407449718, 632.276043929698, 645.3320812958659, 705.6154395854272, 706.8798304288283, 731.2175971710219, 9285.303602229726, 9451.243900487945, 9623.951880240555, 9872.210223182128, 9954.203663706887, 630.2877748144659, 634.4987459906622, 718.9352958233784, 728.471857903016, 754.1289810269147, 4608.193566125915, 4637.930431043537, 4815.15734746425, 4908.8772780680765, 5018.623603229772, 69687.0, 69923.3007669927, 69999.3000070003, 111067.0, 117726.41136794433, 97294.0, 101968.0, 102353.48823255984, 103748.48125759323, 105554.0, 56479.71760141255, 56837.43162568322, 78534.21465785384, 78958.21041789508, 80026.59986700029, 9032.322575806973, 9603.519824008632, 9875.802483950138, 10425.113865321533, 10590.8411373831, 52944.0, 55122.17316740172, 55724.0, 55947.44052559585, 56322.43677563172, 70679.0, 70929.0, 88074.11925880659, 89081.0, 90157.54921225352, 10934.289271197347, 11146.164037697366, 11519.07847372225, 11543.191976561897, 11685.649430516758, 12940.223586584834, 13071.803922940973, 14432.0, 14501.0, 14796.7780483295, 13289.66775830612, 13402.797530827404, 13761.449542018207, 13941.651458713613, 14479.565613031626, 13354.0, 13458.596242112751, 13521.59435216945, 14123.435062597791, 14507.782383264048, 13480.123791953492, 13619.31903404806, 13669.863301367257, 13890.097143685629, 13993.580192594238, 10113.595456181965, 10881.891181088404, 10884.183686223723, 10923.344599324066, 11064.0, 10943.616973405893, 11429.714257143638, 11514.0, 11841.940790295994, 11941.76116477683], "value": 7617.5264262135415, "unit": "score"}], "name": "dromaeo_css", "value": 7601.164557527974, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 118.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 144.0, 173.0, 122.0, 148.0, 144.0, 117.0, 176.0, 115.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 160.0, 159.0, 159.0, 159.0, 160.0, 160.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 184.0, 76.0, 73.0, 74.0, 75.0, 176.0, 75.0], "value": 96.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 153.0, 84.0, 85.0, 85.0, 149.0, 84.0, 84.0, 138.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 148.0, 147.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 52.0, 49.0, 49.0, 50.0, 53.0, 58.0, 51.0, 49.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 61.0, 61.0, 60.0, 61.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 81.0, 72.0, 76.0, 78.0, 73.0, 75.0, 73.0, 73.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 126.0, 128.0, 128.0, 131.0, 116.0, 123.0, 120.0, 159.0, 117.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 154.0, 161.0, 155.0, 157.0, 156.0, 154.0, 153.0, 154.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 65.0, 66.0, 64.0, 65.0, 63.0, 63.0, 61.0, 65.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1505.8, "alertThreshold": 2.0}]} 05:53:37 INFO - Return code: 0 05:53:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:37 INFO - # TBPL SUCCESS # 05:53:37 INFO - Running post-action listener: _resource_record_post_action 05:53:37 INFO - [mozharness: 2016-10-31 12:53:37.307153Z] Finished run-tests step (success) 05:53:37 INFO - Running post-run listener: _resource_record_post_run 05:53:37 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289783808; Read time: 16; Write time: 257664 05:53:37 INFO - TinderboxPrint: CPU usage
13.0% 05:53:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:53:37 INFO - TinderboxPrint: I/O write bytes / time
289,783,808 / 257,664 05:53:37 INFO - TinderboxPrint: CPU idle
8,244.5 (87.0%) 05:53:37 INFO - TinderboxPrint: CPU user
1,187.0 (12.5%) 05:53:37 INFO - TinderboxPrint: Swap in / out
0 / 0 05:53:37 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71282688; Read time: 0; Write time: 112644 05:53:37 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 218501120; Read time: 0; Write time: 145020 05:53:37 INFO - Running post-run listener: _upload_blobber_files 05:53:37 INFO - Blob upload gear active. 05:53:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53: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 05:53: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'] 05:53: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 05:53:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:39 INFO - (blobuploader) - INFO - Done attempting. 05:53:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:53:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:39 INFO - (blobuploader) - INFO - Done attempting. 05:53:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:46 INFO - (blobuploader) - INFO - Done attempting. 05:53:46 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:46 INFO - Return code: 0 05:53:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b2f8ccbc19c95ac870535e67c928333058205ea90b543eea187e49c5b696e3d813d4d4c23d4208d285c1e571e94221fcf4b3210a7b332321a64c497f0c8e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/839d83e3693f06e9ce14740a4af6def4c574dff7bbdcafbeec2a461e46078c2172b6a8d6d1d4d4d701ea141fbbb032f422ece18bdd629f9994f3aaa28bd51f1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33b0cb72610e8ff2b5f0cce88072ab05f2b6b6624833e70731298889fd1c2a55b7fd24401219ac059aa596115dfa1ed182d173e8e7a249227bbc45c5d6f3de7a"} 05:53:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:46 INFO - Contents: 05:53:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b2f8ccbc19c95ac870535e67c928333058205ea90b543eea187e49c5b696e3d813d4d4c23d4208d285c1e571e94221fcf4b3210a7b332321a64c497f0c8e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/839d83e3693f06e9ce14740a4af6def4c574dff7bbdcafbeec2a461e46078c2172b6a8d6d1d4d4d701ea141fbbb032f422ece18bdd629f9994f3aaa28bd51f1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33b0cb72610e8ff2b5f0cce88072ab05f2b6b6624833e70731298889fd1c2a55b7fd24401219ac059aa596115dfa1ed182d173e8e7a249227bbc45c5d6f3de7a"} 05:53:46 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:46 INFO - Copying logs to upload dir... 05:53:46 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.055196 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-10-31 05:53:46.264917) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 05:53:46.268761) ========= 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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 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/23b2f8ccbc19c95ac870535e67c928333058205ea90b543eea187e49c5b696e3d813d4d4c23d4208d285c1e571e94221fcf4b3210a7b332321a64c497f0c8e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/839d83e3693f06e9ce14740a4af6def4c574dff7bbdcafbeec2a461e46078c2172b6a8d6d1d4d4d701ea141fbbb032f422ece18bdd629f9994f3aaa28bd51f1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33b0cb72610e8ff2b5f0cce88072ab05f2b6b6624833e70731298889fd1c2a55b7fd24401219ac059aa596115dfa1ed182d173e8e7a249227bbc45c5d6f3de7a"} build_url:https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/SH8Fj5DnTm2XsTPzpQUvZQ/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/23b2f8ccbc19c95ac870535e67c928333058205ea90b543eea187e49c5b696e3d813d4d4c23d4208d285c1e571e94221fcf4b3210a7b332321a64c497f0c8e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/839d83e3693f06e9ce14740a4af6def4c574dff7bbdcafbeec2a461e46078c2172b6a8d6d1d4d4d701ea141fbbb032f422ece18bdd629f9994f3aaa28bd51f1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33b0cb72610e8ff2b5f0cce88072ab05f2b6b6624833e70731298889fd1c2a55b7fd24401219ac059aa596115dfa1ed182d173e8e7a249227bbc45c5d6f3de7a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 05:53:46.293997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:53:46.294331) ========= 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=17051fcd5208b27b50bedfeb000001dd-1477911977.647232-514399096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:53:46.379171) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 05:53:46.379491) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 05:53:46.379782) ========= ========= Total master_lag: 0.18 =========