builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1473341242.32 results: success (0) buildid: 20160908033016 builduid: e5827c1c5f3247ccaff903a7b16caa87 revision: c580c468fdb4a396bde95c2d2b69d2302e88d3ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.320712) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.321162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.321471) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.335982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.336360) ========= 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=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 06:27:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.5M=0s 2016-09-08 06:27:22 (34.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120475 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.474177) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.474500) ========= 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=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018842 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.524169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:22.524511) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c580c468fdb4a396bde95c2d2b69d2302e88d3ba --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c580c468fdb4a396bde95c2d2b69d2302e88d3ba --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=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 06:27:22,677 truncating revision to first 12 chars 2016-09-08 06:27:22,677 Setting DEBUG logging. 2016-09-08 06:27:22,677 attempt 1/10 2016-09-08 06:27:22,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c580c468fdb4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 06:27:22,980 unpacking tar archive at: mozilla-aurora-c580c468fdb4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:23.202723) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:23.203072) ========= script_repo_revision: c580c468fdb4a396bde95c2d2b69d2302e88d3ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:23.203506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:23.203945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 06:27:23.214248) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-09-08 06:27:23.214767) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:27:23 INFO - MultiFileLogger online at 20160908 06:27:23 in /builds/slave/test 06:27:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:27:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:27:23 INFO - {'append_to_log': False, 06:27:23 INFO - 'base_work_dir': '/builds/slave/test', 06:27:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:27:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:27:23 INFO - 'branch': 'Mozilla-Aurora', 06:27:23 INFO - 'buildbot_json_path': 'buildprops.json', 06:27:23 INFO - 'config_files': ('talos/linux_config.py',), 06:27:23 INFO - 'default_actions': ('clobber', 06:27:23 INFO - 'read-buildbot-config', 06:27:23 INFO - 'download-and-extract', 06:27:23 INFO - 'populate-webroot', 06:27:23 INFO - 'create-virtualenv', 06:27:23 INFO - 'install', 06:27:23 INFO - 'run-tests'), 06:27:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:27:23 INFO - 'download_minidump_stackwalk': True, 06:27:23 INFO - 'download_symbols': 'ondemand', 06:27:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:27:23 INFO - 'tooltool.py': '/tools/tooltool.py', 06:27:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:27:23 INFO - '/tools/misc-python/virtualenv.py')}, 06:27:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:27:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:27:23 INFO - 'installer_path': 'installer.exe', 06:27:23 INFO - 'log_level': 'info', 06:27:23 INFO - 'log_name': 'talos', 06:27:23 INFO - 'log_to_console': True, 06:27:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:27:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:27:23 INFO - 'opt_config_files': (), 06:27:23 INFO - 'pip_index': False, 06:27:23 INFO - 'sps_profile': False, 06:27:23 INFO - 'sps_profile_interval': 0, 06:27:23 INFO - 'suite': 'dromaeojs', 06:27:23 INFO - 'system_bits': '32', 06:27:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:27:23 INFO - 'title': 'talos-linux64-ix-017', 06:27:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:27:23 INFO - 'use_talos_json': True, 06:27:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:27:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:27:23 INFO - 'work_dir': 'build'} 06:27:23 INFO - [mozharness: 2016-09-08 13:27:23.461703Z] Running clobber step. 06:27:23 INFO - Running pre-action listener: _resource_record_pre_action 06:27:23 INFO - Running main action method: clobber 06:27:23 INFO - rmtree: /builds/slave/test/build 06:27:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:27:23 INFO - Running post-action listener: _resource_record_post_action 06:27:23 INFO - [mozharness: 2016-09-08 13:27:23.913823Z] Finished clobber step (success) 06:27:23 INFO - [mozharness: 2016-09-08 13:27:23.913923Z] Running read-buildbot-config step. 06:27:23 INFO - Running pre-action listener: _resource_record_pre_action 06:27:23 INFO - Running main action method: read_buildbot_config 06:27:23 INFO - Using buildbot properties: 06:27:23 INFO - { 06:27:23 INFO - "project": "", 06:27:23 INFO - "product": "firefox", 06:27:23 INFO - "script_repo_revision": "production", 06:27:23 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:27:23 INFO - "repository": "", 06:27:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:27:23 INFO - "buildid": "20160908033016", 06:27:23 INFO - "pgo_build": "True", 06:27:23 INFO - "basedir": "/builds/slave/test", 06:27:23 INFO - "buildnumber": 741, 06:27:23 INFO - "slavename": "talos-linux64-ix-017", 06:27:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:27:23 INFO - "platform": "ubuntu64_hw", 06:27:23 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:27:23 INFO - "branch": "mozilla-aurora", 06:27:23 INFO - "repo_path": "releases/mozilla-aurora", 06:27:23 INFO - "revision": "c580c468fdb4a396bde95c2d2b69d2302e88d3ba", 06:27:23 INFO - "stage_platform": "linux64-pgo", 06:27:23 INFO - "builduid": "e5827c1c5f3247ccaff903a7b16caa87", 06:27:23 INFO - "slavebuilddir": "test" 06:27:23 INFO - } 06:27:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:27:23 INFO - Running post-action listener: _resource_record_post_action 06:27:23 INFO - [mozharness: 2016-09-08 13:27:23.916361Z] Finished read-buildbot-config step (success) 06:27:23 INFO - [mozharness: 2016-09-08 13:27:23.916448Z] Running download-and-extract step. 06:27:23 INFO - Running pre-action listener: _resource_record_pre_action 06:27:23 INFO - Running main action method: download_and_extract 06:27:23 INFO - mkdir: /builds/slave/test/build/tests 06:27:23 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')]} 06:27:23 INFO - https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:27:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:27:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:27:23 INFO - retry: Failed, sleeping 30 seconds before retrying 06:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 06:27:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:27:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:27:53 INFO - retry: Failed, sleeping 60 seconds before retrying 06:28:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 06:28:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:28:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:28:54 INFO - Caught exception: HTTP Error 404: Not Found 06:28:54 INFO - Caught exception: HTTP Error 404: Not Found 06:28:54 INFO - Caught exception: HTTP Error 404: Not Found 06:28:54 INFO - trying https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:54 INFO - Downloading https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:28:54 INFO - Downloaded 1361 bytes. 06:28:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:28:54 INFO - Using the following test package requirements: 06:28:54 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:28:54 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:28:54 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:28:54 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'jsshell-linux-x86_64.zip'], 06:28:54 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:28:54 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:28:54 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:28:54 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:28:54 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:28:54 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:28:54 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:28:54 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:28: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')]} 06:28:54 INFO - https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:28:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:28:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:28:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:28:54 INFO - retry: Failed, sleeping 30 seconds before retrying 06:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:29:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:29:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:29:24 INFO - retry: Failed, sleeping 60 seconds before retrying 06:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:30:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:30:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:30:24 INFO - Caught exception: HTTP Error 404: Not Found 06:30:24 INFO - Caught exception: HTTP Error 404: Not Found 06:30:24 INFO - Caught exception: HTTP Error 404: Not Found 06:30:24 INFO - trying https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:24 INFO - Downloading https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:30:25 INFO - Downloaded 21769736 bytes. 06:30:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:30:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:30:26 INFO - Return code: 0 06:30:26 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:30:26 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')]} 06:30:26 INFO - https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:30:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:30:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:30:26 INFO - retry: Failed, sleeping 30 seconds before retrying 06:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:30:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:30:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:30:56 INFO - retry: Failed, sleeping 60 seconds before retrying 06:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:31:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:31:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:31:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:31:56 INFO - Caught exception: HTTP Error 404: Not Found 06:31:56 INFO - Caught exception: HTTP Error 404: Not Found 06:31:56 INFO - Caught exception: HTTP Error 404: Not Found 06:31:56 INFO - trying https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:31:56 INFO - Downloading https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:31:57 INFO - Downloaded 21769736 bytes. 06:31:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:31:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:31:59 INFO - Return code: 0 06:31:59 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')]} 06:31:59 INFO - https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:31:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:31:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:31:59 INFO - retry: Failed, sleeping 30 seconds before retrying 06:32:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 06:32:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:32:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:32:29 INFO - retry: Failed, sleeping 60 seconds before retrying 06:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 06:33:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:33:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:33:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 06:33:29 INFO - Caught exception: HTTP Error 404: Not Found 06:33:29 INFO - Caught exception: HTTP Error 404: Not Found 06:33:29 INFO - Caught exception: HTTP Error 404: Not Found 06:33:29 INFO - trying https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:33:29 INFO - Downloading https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:33:29 INFO - Downloaded 14413740 bytes. 06:33:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:33:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:33:30 INFO - Return code: 0 06: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')]} 06:33:30 INFO - https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:33:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:33:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:33:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:33:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:33:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:33:30 INFO - retry: Failed, sleeping 30 seconds before retrying 06:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 06:34:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:34:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:34:00 INFO - retry: Failed, sleeping 60 seconds before retrying 06:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 06:35:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:35:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:35:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 06:35:00 INFO - Caught exception: HTTP Error 404: Not Found 06:35:00 INFO - Caught exception: HTTP Error 404: Not Found 06:35:00 INFO - Caught exception: HTTP Error 404: Not Found 06:35:00 INFO - trying https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:35:00 INFO - Downloading https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:35:01 INFO - Downloaded 63898150 bytes. 06:35:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:35:01 INFO - mkdir: /builds/slave/test/properties 06:35:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:35:01 INFO - Writing to file /builds/slave/test/properties/build_url 06:35:01 INFO - Contents: 06:35:01 INFO - build_url:https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:35:02 INFO - Running post-action listener: _resource_record_post_action 06:35:02 INFO - Running post-action listener: set_extra_try_arguments 06:35:02 INFO - [mozharness: 2016-09-08 13:35:02.121912Z] Finished download-and-extract step (success) 06:35:02 INFO - [mozharness: 2016-09-08 13:35:02.122150Z] Running populate-webroot step. 06:35:02 INFO - Running pre-action listener: _resource_record_pre_action 06:35:02 INFO - Running main action method: populate_webroot 06:35:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:35:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:35:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:35:02 INFO - '--tppagecycles', 06:35:02 INFO - '7'], 06:35:02 INFO - 'tests': ['tsvgm']}}, 06:35:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:35:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:35:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:35:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:35:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['damp', 'tps']}, 06:35:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'tests': ['damp', 'tps']}, 06:35:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['dromaeo_dom']}, 06:35:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:35:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['basic_compositor_video']}, 06:35:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:35:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'other-e10s': {'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['a11yr', 06:35:02 INFO - 'ts_paint', 06:35:02 INFO - 'tpaint', 06:35:02 INFO - 'sessionrestore', 06:35:02 INFO - 'sessionrestore_no_auto_restore', 06:35:02 INFO - 'tabpaint']}, 06:35:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['tsvgx', 06:35:02 INFO - 'tsvgr_opacity', 06:35:02 INFO - 'tart', 06:35:02 INFO - 'tscrollx', 06:35:02 INFO - 'cart']}, 06:35:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:35:02 INFO - 'tsvgr_opacity', 06:35:02 INFO - 'tart', 06:35:02 INFO - 'tscrollx', 06:35:02 INFO - 'cart']}, 06:35:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'talos_options': ['--disable-e10s'], 06:35:02 INFO - 'tests': ['tp5o']}, 06:35:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'tests': ['tp5o']}, 06:35:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'talos_options': ['--disable-e10s', 06:35:02 INFO - '--xperf_path', 06:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:02 INFO - 'tests': ['tp5n']}, 06:35:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:02 INFO - 'talos_options': ['--xperf_path', 06:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:02 INFO - 'tests': ['tp5n']}}, 06:35:02 INFO - 'talos.zip': {'path': '', 06:35:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:35:02 INFO - Running post-action listener: _resource_record_post_action 06:35:02 INFO - [mozharness: 2016-09-08 13:35:02.142575Z] Finished populate-webroot step (success) 06:35:02 INFO - [mozharness: 2016-09-08 13:35:02.142664Z] Running create-virtualenv step. 06:35:02 INFO - Running pre-action listener: _resource_record_pre_action 06:35:02 INFO - Running main action method: create_virtualenv 06:35:02 INFO - Creating virtualenv /builds/slave/test/build/venv 06:35:02 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 06:35:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:35:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:35:02 INFO - Using real prefix '/usr' 06:35:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:35:02 INFO - Installing distribute.............................................................................................................................................................................................done. 06:35:04 INFO - Installing pip.................done. 06:35:04 INFO - Return code: 0 06:35:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:04 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:04 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 06:35:04 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 06:35:04 INFO - Using env: {'DISPLAY': ':0', 06:35:04 INFO - 'HOME': '/home/cltbld', 06:35:04 INFO - 'LANG': 'en_US.UTF-8', 06:35:04 INFO - 'LANGUAGE': 'en_US:en', 06:35:04 INFO - 'LOGNAME': 'cltbld', 06:35:04 INFO - 'MAIL': '/var/mail/cltbld', 06:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:04 INFO - 'MOZ_NO_REMOTE': '1', 06:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:04 INFO - 'NO_EM_RESTART': '1', 06:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:04 INFO - 'PWD': '/builds/slave/test', 06:35:04 INFO - 'SHELL': '/bin/bash', 06:35:04 INFO - 'SHLVL': '1', 06:35:04 INFO - 'TERM': 'linux', 06:35:04 INFO - 'TMOUT': '86400', 06:35:04 INFO - 'USER': 'cltbld', 06:35:04 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:04 INFO - '_': '/tools/buildbot/bin/python'} 06:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:04 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 06:35:04 INFO - Cleaning up... 06:35:04 INFO - Return code: 0 06:35:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:04 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:04 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 06:35:04 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 06:35:04 INFO - Using env: {'DISPLAY': ':0', 06:35:04 INFO - 'HOME': '/home/cltbld', 06:35:04 INFO - 'LANG': 'en_US.UTF-8', 06:35:04 INFO - 'LANGUAGE': 'en_US:en', 06:35:04 INFO - 'LOGNAME': 'cltbld', 06:35:04 INFO - 'MAIL': '/var/mail/cltbld', 06:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:04 INFO - 'MOZ_NO_REMOTE': '1', 06:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:04 INFO - 'NO_EM_RESTART': '1', 06:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:04 INFO - 'PWD': '/builds/slave/test', 06:35:04 INFO - 'SHELL': '/bin/bash', 06:35:04 INFO - 'SHLVL': '1', 06:35:04 INFO - 'TERM': 'linux', 06:35:04 INFO - 'TMOUT': '86400', 06:35:04 INFO - 'USER': 'cltbld', 06:35:04 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:04 INFO - '_': '/tools/buildbot/bin/python'} 06:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:05 INFO - Downloading/unpacking psutil>=3.1.1 06:35:05 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 06:35:05 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 06:35:05 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 06:35:05 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 06:35:05 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 06:35:05 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 06:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:35:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:07 INFO - Installing collected packages: psutil 06:35:07 INFO - Running setup.py install for psutil 06:35:07 INFO - building 'psutil._psutil_linux' extension 06:35:07 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 06:35:07 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 06:35:07 INFO - building 'psutil._psutil_posix' extension 06:35:07 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 06:35:07 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 06:35:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:07 INFO - Successfully installed psutil 06:35:07 INFO - Cleaning up... 06:35:07 INFO - Return code: 0 06:35:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:07 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:07 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 06:35:07 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 06:35:07 INFO - Using env: {'DISPLAY': ':0', 06:35:07 INFO - 'HOME': '/home/cltbld', 06:35:07 INFO - 'LANG': 'en_US.UTF-8', 06:35:07 INFO - 'LANGUAGE': 'en_US:en', 06:35:07 INFO - 'LOGNAME': 'cltbld', 06:35:07 INFO - 'MAIL': '/var/mail/cltbld', 06:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:07 INFO - 'MOZ_NO_REMOTE': '1', 06:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:07 INFO - 'NO_EM_RESTART': '1', 06:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:07 INFO - 'PWD': '/builds/slave/test', 06:35:07 INFO - 'SHELL': '/bin/bash', 06:35:07 INFO - 'SHLVL': '1', 06:35:07 INFO - 'TERM': 'linux', 06:35:07 INFO - 'TMOUT': '86400', 06:35:07 INFO - 'USER': 'cltbld', 06:35:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:07 INFO - '_': '/tools/buildbot/bin/python'} 06:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:35:09 INFO - Installing collected packages: mozsystemmonitor 06:35:09 INFO - Running setup.py install for mozsystemmonitor 06:35:09 INFO - Successfully installed mozsystemmonitor 06:35:09 INFO - Cleaning up... 06:35:09 INFO - Return code: 0 06:35:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:35:09 INFO - Using env: {'DISPLAY': ':0', 06:35:09 INFO - 'HOME': '/home/cltbld', 06:35:09 INFO - 'LANG': 'en_US.UTF-8', 06:35:09 INFO - 'LANGUAGE': 'en_US:en', 06:35:09 INFO - 'LOGNAME': 'cltbld', 06:35:09 INFO - 'MAIL': '/var/mail/cltbld', 06:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:09 INFO - 'MOZ_NO_REMOTE': '1', 06:35:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:09 INFO - 'NO_EM_RESTART': '1', 06:35:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:09 INFO - 'PWD': '/builds/slave/test', 06:35:09 INFO - 'SHELL': '/bin/bash', 06:35:09 INFO - 'SHLVL': '1', 06:35:09 INFO - 'TERM': 'linux', 06:35:09 INFO - 'TMOUT': '86400', 06:35:09 INFO - 'USER': 'cltbld', 06:35:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:09 INFO - '_': '/tools/buildbot/bin/python'} 06:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:10 INFO - Downloading/unpacking jsonschema==2.5.1 06:35:10 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 06:35:10 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 06:35:10 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 06:35:10 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 06:35:10 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 06:35:10 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 06:35:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:35:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 INFO - Downloading functools32-3.2.3-2.tar.gz 06:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:35:12 INFO - warning: no files found matching '*.txt' 06:35:12 INFO - no previously-included directories found matching 'build' 06:35:12 INFO - no previously-included directories found matching 'dist' 06:35:12 INFO - no previously-included directories found matching '.git*' 06:35:12 INFO - Installing collected packages: jsonschema, functools32 06:35:12 INFO - Running setup.py install for functools32 06:35:12 INFO - warning: no files found matching '*.txt' 06:35:12 INFO - no previously-included directories found matching 'build' 06:35:12 INFO - no previously-included directories found matching 'dist' 06:35:12 INFO - no previously-included directories found matching '.git*' 06:35:12 INFO - Successfully installed jsonschema functools32 06:35:12 INFO - Cleaning up... 06:35:12 INFO - Return code: 0 06:35:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:12 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:12 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 06:35:12 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 06:35:12 INFO - Using env: {'DISPLAY': ':0', 06:35:12 INFO - 'HOME': '/home/cltbld', 06:35:12 INFO - 'LANG': 'en_US.UTF-8', 06:35:12 INFO - 'LANGUAGE': 'en_US:en', 06:35:12 INFO - 'LOGNAME': 'cltbld', 06:35:12 INFO - 'MAIL': '/var/mail/cltbld', 06:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:12 INFO - 'MOZ_NO_REMOTE': '1', 06:35:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:12 INFO - 'NO_EM_RESTART': '1', 06:35:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:12 INFO - 'PWD': '/builds/slave/test', 06:35:12 INFO - 'SHELL': '/bin/bash', 06:35:12 INFO - 'SHLVL': '1', 06:35:12 INFO - 'TERM': 'linux', 06:35:12 INFO - 'TMOUT': '86400', 06:35:12 INFO - 'USER': 'cltbld', 06:35:12 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:12 INFO - '_': '/tools/buildbot/bin/python'} 06:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:35:12 INFO - Cleaning up... 06:35:12 INFO - Return code: 0 06:35:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:12 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:12 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 06:35:12 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 06:35:12 INFO - Using env: {'DISPLAY': ':0', 06:35:12 INFO - 'HOME': '/home/cltbld', 06:35:12 INFO - 'LANG': 'en_US.UTF-8', 06:35:12 INFO - 'LANGUAGE': 'en_US:en', 06:35:12 INFO - 'LOGNAME': 'cltbld', 06:35:12 INFO - 'MAIL': '/var/mail/cltbld', 06:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:12 INFO - 'MOZ_NO_REMOTE': '1', 06:35:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:12 INFO - 'NO_EM_RESTART': '1', 06:35:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:12 INFO - 'PWD': '/builds/slave/test', 06:35:12 INFO - 'SHELL': '/bin/bash', 06:35:12 INFO - 'SHLVL': '1', 06:35:12 INFO - 'TERM': 'linux', 06:35:12 INFO - 'TMOUT': '86400', 06:35:12 INFO - 'USER': 'cltbld', 06:35:12 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:12 INFO - '_': '/tools/buildbot/bin/python'} 06:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:12 INFO - Downloading/unpacking blobuploader==1.2.4 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:12 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 06:35:14 INFO - Downloading blobuploader-1.2.4.tar.gz 06:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:35:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:35:14 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 06:35:14 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 06:35:14 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 06:35:14 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 06:35:14 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 06:35:14 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 06:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:35:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:35:15 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 06:35:15 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 06:35:15 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 06:35:15 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 06:35:15 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 06:35:15 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 06:35:15 INFO - Downloading docopt-0.6.1.tar.gz 06:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:35:15 INFO - Installing collected packages: blobuploader, requests, docopt 06:35:15 INFO - Running setup.py install for blobuploader 06:35:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:35:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:35:15 INFO - Running setup.py install for requests 06:35:16 INFO - Running setup.py install for docopt 06:35:16 INFO - Successfully installed blobuploader requests docopt 06:35:16 INFO - Cleaning up... 06:35:16 INFO - Return code: 0 06:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:16 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:16 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 06:35:16 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 06:35:16 INFO - Using env: {'DISPLAY': ':0', 06:35:16 INFO - 'HOME': '/home/cltbld', 06:35:16 INFO - 'LANG': 'en_US.UTF-8', 06:35:16 INFO - 'LANGUAGE': 'en_US:en', 06:35:16 INFO - 'LOGNAME': 'cltbld', 06:35:16 INFO - 'MAIL': '/var/mail/cltbld', 06:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:16 INFO - 'MOZ_NO_REMOTE': '1', 06:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:16 INFO - 'NO_EM_RESTART': '1', 06:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:16 INFO - 'PWD': '/builds/slave/test', 06:35:16 INFO - 'SHELL': '/bin/bash', 06:35:16 INFO - 'SHLVL': '1', 06:35:16 INFO - 'TERM': 'linux', 06:35:16 INFO - 'TMOUT': '86400', 06:35:16 INFO - 'USER': 'cltbld', 06:35:16 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:16 INFO - '_': '/tools/buildbot/bin/python'} 06:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:35:16 INFO - Running setup.py (path:/tmp/pip-EiBpGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:35:16 INFO - Running setup.py (path:/tmp/pip-eYdKeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:35:16 INFO - Running setup.py (path:/tmp/pip-H7N1BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:35:16 INFO - Running setup.py (path:/tmp/pip-JwcHmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:35:16 INFO - Running setup.py (path:/tmp/pip-QxepLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:35:16 INFO - Running setup.py (path:/tmp/pip-Wqvzjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:35:16 INFO - Running setup.py (path:/tmp/pip-QwDv4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:35:16 INFO - Running setup.py (path:/tmp/pip-GaiZlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:35:17 INFO - Running setup.py (path:/tmp/pip-6EEBPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:35:17 INFO - Running setup.py (path:/tmp/pip-RfHtdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:35:17 INFO - Running setup.py (path:/tmp/pip-P7ZrWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:35:17 INFO - Running setup.py (path:/tmp/pip-W_3gpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:35:17 INFO - Running setup.py (path:/tmp/pip-kuvBeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:35:17 INFO - Running setup.py (path:/tmp/pip-IvHGET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:17 INFO - Running setup.py (path:/tmp/pip-swBvF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:35:17 INFO - Running setup.py (path:/tmp/pip-lv2q_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:35:17 INFO - Running setup.py (path:/tmp/pip-VbQhqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:35:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:35:17 INFO - Running setup.py install for manifestparser 06:35:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:35:17 INFO - Running setup.py install for mozcrash 06:35:17 INFO - Running setup.py install for mozdebug 06:35:17 INFO - Running setup.py install for mozdevice 06:35:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for mozfile 06:35:18 INFO - Running setup.py install for mozhttpd 06:35:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for mozinfo 06:35:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for mozInstall 06:35:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for mozleak 06:35:18 INFO - Running setup.py install for mozlog 06:35:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for moznetwork 06:35:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:35:18 INFO - Running setup.py install for mozprocess 06:35:18 INFO - Running setup.py install for mozprofile 06:35:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:35:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:35:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:35:19 INFO - Running setup.py install for mozrunner 06:35:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:35:19 INFO - Running setup.py install for mozscreenshot 06:35:19 INFO - Running setup.py install for moztest 06:35:19 INFO - Running setup.py install for mozversion 06:35:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:35:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:35:19 INFO - Cleaning up... 06:35:19 INFO - Return code: 0 06:35:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:19 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:19 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 06:35:19 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 06:35:19 INFO - Using env: {'DISPLAY': ':0', 06:35:19 INFO - 'HOME': '/home/cltbld', 06:35:19 INFO - 'LANG': 'en_US.UTF-8', 06:35:19 INFO - 'LANGUAGE': 'en_US:en', 06:35:19 INFO - 'LOGNAME': 'cltbld', 06:35:19 INFO - 'MAIL': '/var/mail/cltbld', 06:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:19 INFO - 'MOZ_NO_REMOTE': '1', 06:35:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:19 INFO - 'NO_EM_RESTART': '1', 06:35:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:19 INFO - 'PWD': '/builds/slave/test', 06:35:19 INFO - 'SHELL': '/bin/bash', 06:35:19 INFO - 'SHLVL': '1', 06:35:19 INFO - 'TERM': 'linux', 06:35:19 INFO - 'TMOUT': '86400', 06:35:19 INFO - 'USER': 'cltbld', 06:35:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:19 INFO - '_': '/tools/buildbot/bin/python'} 06:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:35:19 INFO - Running setup.py (path:/tmp/pip-8mjMDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:35:19 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)) 06:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:35:19 INFO - Running setup.py (path:/tmp/pip-rC5duo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:35:19 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)) 06:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:35:19 INFO - Running setup.py (path:/tmp/pip-HyHMHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:35:19 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)) 06:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:35:19 INFO - Running setup.py (path:/tmp/pip-BfxNU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:35:19 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:35:20 INFO - Running setup.py (path:/tmp/pip-55qp4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:35:20 INFO - Running setup.py (path:/tmp/pip-kuAcdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:35:20 INFO - Running setup.py (path:/tmp/pip-9FoufL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:35:20 INFO - Running setup.py (path:/tmp/pip-SmzCA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:35:20 INFO - Running setup.py (path:/tmp/pip-L1ReoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:35:20 INFO - Running setup.py (path:/tmp/pip-kITJZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:35:20 INFO - Running setup.py (path:/tmp/pip-CNg8QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:35:20 INFO - Running setup.py (path:/tmp/pip-Ig9NeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:35:20 INFO - Running setup.py (path:/tmp/pip-mp1FzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:35:20 INFO - Running setup.py (path:/tmp/pip-SyaBxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:20 INFO - Running setup.py (path:/tmp/pip-WEx7ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:35:20 INFO - Running setup.py (path:/tmp/pip-GG24I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:35:20 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)) 06:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:35:20 INFO - Running setup.py (path:/tmp/pip-WFKks4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:35:20 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)) 06:35:20 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)) 06:35:20 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)) 06:35:20 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)) 06:35:20 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)) 06:35:20 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)) 06:35:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:22 INFO - Downloading blessings-1.6.tar.gz 06:35:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:35:22 INFO - Installing collected packages: blessings 06:35:22 INFO - Running setup.py install for blessings 06:35:23 INFO - Successfully installed blessings 06:35:23 INFO - Cleaning up... 06:35:23 INFO - Return code: 0 06:35:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:35:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:35:23 INFO - Reading from file tmpfile_stdout 06:35:23 INFO - Current package versions: 06:35:23 INFO - argparse == 1.2.1 06:35:23 INFO - blessings == 1.6 06:35:23 INFO - blobuploader == 1.2.4 06:35:23 INFO - docopt == 0.6.1 06:35:23 INFO - functools32 == 3.2.3-2 06:35:23 INFO - jsonschema == 2.5.1 06:35:23 INFO - manifestparser == 1.1 06:35:23 INFO - mozInstall == 1.12 06:35:23 INFO - mozcrash == 0.17 06:35:23 INFO - mozdebug == 0.1 06:35:23 INFO - mozdevice == 0.48 06:35:23 INFO - mozfile == 1.2 06:35:23 INFO - mozhttpd == 0.7 06:35:23 INFO - mozinfo == 0.9 06:35:23 INFO - mozleak == 0.1 06:35:23 INFO - mozlog == 3.3 06:35:23 INFO - moznetwork == 0.27 06:35:23 INFO - mozprocess == 0.23 06:35:23 INFO - mozprofile == 0.28 06:35:23 INFO - mozrunner == 6.12 06:35:23 INFO - mozscreenshot == 0.1 06:35:23 INFO - mozsystemmonitor == 0.3 06:35:23 INFO - moztest == 0.7 06:35:23 INFO - mozversion == 1.4 06:35:23 INFO - psutil == 3.1.1 06:35:23 INFO - requests == 1.2.3 06:35:23 INFO - wsgiref == 0.1.2 06:35:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:23 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')]} 06:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:23 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')]} 06:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:23 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:23 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 06:35:23 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 06:35:23 INFO - Using env: {'DISPLAY': ':0', 06:35:23 INFO - 'HOME': '/home/cltbld', 06:35:23 INFO - 'LANG': 'en_US.UTF-8', 06:35:23 INFO - 'LANGUAGE': 'en_US:en', 06:35:23 INFO - 'LOGNAME': 'cltbld', 06:35:23 INFO - 'MAIL': '/var/mail/cltbld', 06:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:23 INFO - 'MOZ_NO_REMOTE': '1', 06:35:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:23 INFO - 'NO_EM_RESTART': '1', 06:35:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:23 INFO - 'PWD': '/builds/slave/test', 06:35:23 INFO - 'SHELL': '/bin/bash', 06:35:23 INFO - 'SHLVL': '1', 06:35:23 INFO - 'TERM': 'linux', 06:35:23 INFO - 'TMOUT': '86400', 06:35:23 INFO - 'USER': 'cltbld', 06:35:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:23 INFO - '_': '/tools/buildbot/bin/python'} 06:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 INFO - Cleaning up... 06:35:23 INFO - Return code: 0 06:35:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:35:23 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')]} 06:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:23 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')]} 06:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:23 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 0x2483030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2744180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, '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': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', '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 06:35:23 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 06:35:23 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 06:35:23 INFO - Using env: {'DISPLAY': ':0', 06:35:23 INFO - 'HOME': '/home/cltbld', 06:35:23 INFO - 'LANG': 'en_US.UTF-8', 06:35:23 INFO - 'LANGUAGE': 'en_US:en', 06:35:23 INFO - 'LOGNAME': 'cltbld', 06:35:23 INFO - 'MAIL': '/var/mail/cltbld', 06:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:23 INFO - 'MOZ_NO_REMOTE': '1', 06:35:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:23 INFO - 'NO_EM_RESTART': '1', 06:35:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:23 INFO - 'PWD': '/builds/slave/test', 06:35:23 INFO - 'SHELL': '/bin/bash', 06:35:23 INFO - 'SHLVL': '1', 06:35:23 INFO - 'TERM': 'linux', 06:35:23 INFO - 'TMOUT': '86400', 06:35:23 INFO - 'USER': 'cltbld', 06:35:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:23 INFO - '_': '/tools/buildbot/bin/python'} 06:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:35:23 INFO - Cleaning up... 06:35:23 INFO - Return code: 0 06:35:23 INFO - Running post-action listener: _resource_record_post_action 06:35:23 INFO - Running post-action listener: _start_resource_monitoring 06:35:23 INFO - Starting resource monitoring. 06:35:23 INFO - [mozharness: 2016-09-08 13:35:23.771100Z] Finished create-virtualenv step (success) 06:35:23 INFO - [mozharness: 2016-09-08 13:35:23.771582Z] Running install step. 06:35:23 INFO - Running pre-action listener: _resource_record_pre_action 06:35:23 INFO - Running main action method: install 06:35:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:35:23 INFO - Reading from file tmpfile_stdout 06:35:23 INFO - Detecting whether we're running mozinstall >=1.0... 06:35:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:35:24 INFO - Reading from file tmpfile_stdout 06:35:24 INFO - Output received: 06:35:24 INFO - Usage: mozinstall [options] installer 06:35:24 INFO - Options: 06:35:24 INFO - -h, --help show this help message and exit 06:35:24 INFO - -d DEST, --destination=DEST 06:35:24 INFO - Directory to install application into. [default: 06:35:24 INFO - "/builds/slave/test"] 06:35:24 INFO - --app=APP Application being installed. [default: firefox] 06:35:24 INFO - mkdir: /builds/slave/test/build/application 06:35:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:35:40 INFO - Reading from file tmpfile_stdout 06:35:40 INFO - Output received: 06:35:40 INFO - /builds/slave/test/build/application/firefox/firefox 06:35:40 INFO - Running post-action listener: _resource_record_post_action 06:35:40 INFO - [mozharness: 2016-09-08 13:35:40.112468Z] Finished install step (success) 06:35:40 INFO - [mozharness: 2016-09-08 13:35:40.112752Z] Running run-tests step. 06:35:40 INFO - Running pre-action listener: _resource_record_pre_action 06:35:40 INFO - Running main action method: run_tests 06:35:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:35:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:35:40 INFO - Python 2.7.3 06:35:40 INFO - Return code: 0 06:35:40 INFO - grabbing minidump binary from tooltool 06:35:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:40 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 0x27423b0>, '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 0x27432b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2743740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:35:40 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 06:35:40 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 06:35:40 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 06:35:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:35:40 INFO - Return code: 0 06:35:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:35:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:35:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:35:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:35:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:35:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 06:35:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 06:35:40 INFO - Using env: {'DISPLAY': ':0', 06:35:40 INFO - 'HOME': '/home/cltbld', 06:35:40 INFO - 'LANG': 'en_US.UTF-8', 06:35:40 INFO - 'LANGUAGE': 'en_US:en', 06:35:40 INFO - 'LOGNAME': 'cltbld', 06:35:40 INFO - 'MAIL': '/var/mail/cltbld', 06:35:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:35:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:40 INFO - 'MOZ_NO_REMOTE': '1', 06:35:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:35:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:40 INFO - 'NO_EM_RESTART': '1', 06:35:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:40 INFO - 'PWD': '/builds/slave/test', 06:35:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:35:40 INFO - 'SHELL': '/bin/bash', 06:35:40 INFO - 'SHLVL': '1', 06:35:40 INFO - 'TERM': 'linux', 06:35:40 INFO - 'TMOUT': '86400', 06:35:40 INFO - 'USER': 'cltbld', 06:35:40 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936', 06:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:40 INFO - '_': '/tools/buildbot/bin/python'} 06:35:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 06:35:40 INFO - mozversion application_buildid: 20160908033016 06:35:40 INFO - mozversion application_changeset: c580c468fdb4a396bde95c2d2b69d2302e88d3ba 06:35:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:35:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:35:40 INFO - mozversion application_name: Firefox 06:35:40 INFO - mozversion application_remotingname: firefox-dev 06:35:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:35:40 INFO - mozversion application_vendor: Mozilla 06:35:40 INFO - mozversion application_version: 50.0a2 06:35:40 INFO - mozversion platform_buildid: 20160908033016 06:35:40 INFO - mozversion platform_changeset: c580c468fdb4a396bde95c2d2b69d2302e88d3ba 06:35:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:35:40 INFO - mozversion platform_version: 50.0a2 06:35:40 INFO - using testdate: 1473341740 06:35:40 INFO - actual date: 1473341740 06:35:40 INFO - starting webserver on 'localhost:42680' 06:35:40 INFO - SUITE-START | Running 2 tests 06:35:40 INFO - TEST-START | dromaeo_css 06:35:40 INFO - operating with platform_type : linux_ 06:35:40 INFO - Initialising browser for dromaeo_css test... 06:35:40 INFO - TEST-INFO | started process 5927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFR8fLB/profile http://localhost:42680/getInfo.html) 06:35:43 INFO - PROCESS | 5927 | __metrics Screen width/height:1600/1200 06:35:43 INFO - PROCESS | 5927 | colorDepth:24 06:35:43 INFO - PROCESS | 5927 | Browser inner width/height: 1024/697 06:35:43 INFO - PROCESS | 5927 | __metrics 06:35:44 INFO - PROCESS | 5927 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:35:44 INFO - PROCESS | 5927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:35:45 INFO - TEST-INFO | 5927: exit 0 06:35:45 INFO - Browser initialized. 06:35:45 INFO - Running cycle 1/1 for dromaeo_css test... 06:35:45 INFO - TEST-INFO | started process 6014 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFR8fLB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:35:57 INFO - PROCESS | 6014 | RSS: Main: 145604608 06:35:57 INFO - PROCESS | 6014 | 06:36:48 INFO - PROCESS | 6014 | 1473341808107 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] 06:38:56 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42680/tests/dromaeo/cssquery-ext.html) 06:38:57 INFO - PROCESS | 6014 | RSS: Main: 273174528 06:38:57 INFO - PROCESS | 6014 | 06:42:00 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-ext.html (next: http://localhost:42680/tests/dromaeo/cssquery-jquery.html) 06:42:00 INFO - PROCESS | 6014 | RSS: Main: 229363712 06:42:00 INFO - PROCESS | 6014 | 06:45:02 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42680/tests/dromaeo/cssquery-mootools.html) 06:45:03 INFO - PROCESS | 6014 | RSS: Main: 175476736 06:45:03 INFO - PROCESS | 6014 | 06:48:05 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42680/tests/dromaeo/cssquery-prototype.html) 06:48:05 INFO - PROCESS | 6014 | RSS: Main: 174108672 06:48:05 INFO - PROCESS | 6014 | 06:51:08 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42680/tests/dromaeo/cssquery-yui.html) 06:51:09 INFO - PROCESS | 6014 | RSS: Main: 175640576 06:51:09 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | Cycle 1(1): loaded http://localhost:42680/tests/dromaeo/cssquery-yui.html (next: http://localhost:42680/tests/dromaeo/cssquery-dojo.html) 06:54:11 INFO - PROCESS | 6014 | RSS: Main: 264355840 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | __start_tp_report 06:54:11 INFO - PROCESS | 6014 | _x_x_mozilla_page_load 06:54:11 INFO - PROCESS | 6014 | _x_x_mozilla_page_load_details 06:54:11 INFO - PROCESS | 6014 | |i|pagename|runs| 06:54:11 INFO - PROCESS | 6014 | |0;dojo.html;3172.952405713914;3181;3217.951730724038;3222.693844084811;3252.723518500927;5485.588580856437;5591.664500129985;5595.664260144384;5605.1872478490595;5618.634788738735;5504.394516603181;5560.4161563035905;5573.526250268732;5575.442455754423;5589;4962.528559786814;4994.150994330972;5001.59987201024;5026.547610715035;5079.898402031976;4964.056829202458;5003.424606170301;5025.04524140414;5031.723255220962;5070.467600901908;5003.974980125113;5080.771365288552;5091.9236211456855;5104.821331253407;5109.105906466372;5137.280780690707;5162.019216348882;5163.63854530183;5164.509371609691;5167.044096842059;4893.853184404438;4894.926576101326;4931.235658472942;5060.84817455477;5097.49025097491;5380.704061276629;5394.244805727201;5406.053940560386;5420.945790542123;5445.782168713246;5840.8026354597205;5845.766169353221;5846.532277417797;5847.356790753014;5848.298204215522;5720.22776925114;5722.141678748179;5752.7986520472;5778.682172480512;5886.499647529965;5478.342598888119;5478.506934375925;5486.862828429242;5502.724863756836;5506.311711036121;5906.143609176645;5928.525717942554;5929.881402371929;5952.791652292236;5961.195238642815;5663.7451314690115;5730.226419433407;5835.708214589254;5904.704764761745;5913.113033044991;5957.195778569924;5958.523318134538;5958.612690175125;5963.224780778558;5986.341502434729;5915.290165180119;5927.9407205928255;5935.73289201987;5937.020391635332;5938.079597662369;5713.257276554022;5732.512736417368;5838.5621078418435;5856.326522449887;5935.2877654681715;4565.178267911808;4595.471520775087;4619.376384188159;4624.028953919715;4629.675922685447;4113.259613269581;4114.567970363129;4118.835246590163;4120.052387950788;4138.193052354762;5438.129899216186;5558.221848941152;5594.272744543265;5696.857578560486;5711.485966263056;5971;5976.760929562769;5996.820095397145;6011.158437818709;6025.216721826135;5638.126090455986;5650.886982260332;5654.660720356792;5659.377468478464;5673.035583950738;4993.375828021497;5064.468230835783;5124.30821839047;5164.096283150434;5165.354330708661;6117.143599896018;6121.4529735759925;6130.47890929276;6136.090214303957;6138.7869253627705;5937.554683398849;5942.168096466585;5949.672767997628;5965.433283838029;5968.313643931004;5921.289445266596;5995.0407934731975;6033.698315084141;6156.5998210116195;6169.413905678954;5834.095715164156;5838.357780644041;5845.240118784531;5862.530997520103;5877.382874798255;5885.882282354243;5937.851553711192;5947.702614869326;5952.910706339322;5955.374685657944;5617.185508101385;5795.797147099831;5809.912851307318;5818.359980402237;5821.738021789114;5908.143319218775;5915.881682366415;5918.526517878646;5934.317553481233;5944.019236825875;5884.735186916513;5894.616849904914;5894.6757928314355;5897.616654917417;5904.996150654313;5721.284839395075;5838.32859221195;5896.734646940984;5922.052471604523;5934.139549765347;5788.8552786179;5789.768409263583;5790;5794.652320860761;5813.09896965971 06:54:11 INFO - PROCESS | 6014 | |1;ext.html;587.806024012076;626.7179769103903;651.4527796650813;654.417568364156;668.4785867023724;35057.649423505754;46853;48318.51681483184;48365.758171209185;48376.758116209414;49493.752531237296;49836.75081624587;49944.50055499444;49956.75021624896;49987;8476.364272679573;9242.214411775007;9387.62449502019;9389.81220375592;9395.953020234889;8428.704995325164;8703.869441958375;8704;10835.404052777094;11122.277051991636;11256.212065155401;11283.37941413226;11299.378534180616;11327.603533876316;11335;11147.777044459106;11231.045361144312;11233.550657973672;11238.213325067249;11241.550337986471;11001.064909482704;11063.557457701681;11067.889321106766;11069.169812264032;11080.722981925435;42649.78675106636;42959;43008.78495607502;43033.56966430327;43052.354214686806;3823.560290566593;3871.2064026874423;3876.6317199865966;4185.518665353493;4531.3429552715;35166.120846978774;37273;37356.81321593402;37546;37593;4814.614830813562;4823.107725070838;4848.369711937461;4877.926831097537;5056.797728090872;383993;387544;387702;389296;389966.0501697502;70253.2974670257;70947.64526177336;71189.64405177941;71237.2876271241;71356.64321678359;41381.1723765523;42337.15325693469;42365.57634423678;44779;44813.55186448159;20727.170913163605;20898.164073437198;20905.89547052255;20939.37181884546;20940.58118837615;14272.643183920485;15528.5341439757;15619.219039048003;15626.45307414235;15634.140122293384;22181.11275548992;22918.885405572866;23252.3024309271;23840.761592383853;24151.63772543413;8277.963730828747;8311.418200726011;8391.454555453876;8481.321494280442;8700.78248043804;1032.8244198486275;1086.3101930274174;1128.0834445597936;1155.185594156118;1160.0893298760432;34995.72527785686;35599.82200088983;35753.642463575554;35890;35953.460698089024;1130.8473356096986;1209.8911098001222;1252.7557126360455;1260.5966090851023;1270.675977625718;17799.288028478975;18725.625487490175;18742.906285468485;18758.437246882615;18794.53013674669;631524;687007;692217;692703.6824079338;1101283;66673.66663166654;67182.32817671761;67304;67329.32670673328;67575;1360.4490181476517;1465.3039806092022;1471.3526048538608;1498.6028702393874;1522.0335087219696;31510;35884.28231435409;36046.63953360433;36059.63940360563;36084.458733118496;54543;55172;55178.172327414315;55227.171592425344;55574.72212638911;471573;472070;473271;475430;476489;28190.577141342485;31805.681943180272;32234.35531289314;33261.50107748434;33348.66651333456;45794;51268;51608.225876611126;51638.48361516487;52878.73560632172;43046.35430468608;43103.35344969765;43628;43753;45437.31844022409;43528.12943741044;43964.78017609892;44375;44408.33387499126;45875.541244588465;43605.345919811865;44373.55626443694;45213.321800172365;45361.773191133834;47968;38309.61690383172;40779.1844163121;40987.38518922278;41560;41680.541181058514;30492.84753576218;30514.84742576362;30646.757218674138;31063.378732425677;31275.218119547193 06:54:11 INFO - PROCESS | 6014 | |2;jquery.html;402.1093278388369;407.559835377792;420.3925327901183;425.56592275878603;436.80562149843314;19865.106070226837;21025.47436314093;21128.154873805066;21332.36002919915;21420.678689819644;16664.75002874958;19764;20080.49798755032;20120;20298.18807247712;5328.8401347959625;5606.607537472379;5607.943920560803;5612.15817627355;5612.971935140318;5333.333333333333;5390.703511306877;5455.18172274158;5476.616636835423;5537.197106419567;5447.836564903039;5448.182772584105;5451.2640793492965;5454.481824226692;5454.563634909197;5330.813421530247;5439.972800135994;5443.782248710047;5540.778368865241;5582.888342233173;5435.782568697247;5444.6188766786345;5447.782088716446;5453.945460545384;5454.34547854256;15087.24563771807;15181.848181518153;15277.38890444392;15326;15334.309956051893;2349.4713689419914;2542.8855701493476;2622.5803871380485;2667.2665017120253;2819.1401622505127;10999.890001099966;11209.159313051554;11359.261647992855;11392.601258955896;11472.4837382318;1998.1907327532372;2017.6267390532798;2029.5839352932614;2128.70198172256;2154.6121698094335;81657.59171204166;87046.56476717639;89153.10846891513;91230.543847281;92468;10408.635697750542;10903.509342079626;11101.666949991513;11241.381724005258;11248.300407902045;15094.320755566026;15100.773488397683;15113.773293400387;15132;15132.848671513364;8744.562771861509;8749.825003499895;8791;8970.551472426352;9184.081591840762;9166.312526560534;9178.586963586655;9188.1730644241;9377.953110234404;9404.95297523508;4626.86119416418;4798.304245884327;4994.076095922266;5005.6996580205305;5127.717975511383;2021.4643119573325;2090.770015298316;2104.9579008419746;2116.6719158530454;2143.720108234404;715.9565501501094;1011.8431643095184;1043.1811028342715;1138.5673444091192;1146.2320245435578;19646;19911;20012.299569515;20108.195672173242;20914.581708365746;1055.472263868066;1086.8369744538381;1093.1637297467444;1133.8922802333766;1137.0448822988728;10747.83878241827;10768.623098191525;11222;11244.212905096729;11248.831267530994;473445;475343;475400;487630;493861;51010;59478.70260648755;65898.34101658923;87259;99365.50317248367;1031.896810318977;1041.7083216699336;1064.7923654887377;1065.6962765611918;1065.8454524093963;17387.21757520889;17903.910480447514;18100.637987240443;18224.4532664015;18252.543686407946;44096.33855492106;44664.55335446604;45090.323645146;45454.545454545034;47568;63203.5238061911;63424.19454832725;63966;64681;64877.67561162164;8595.699150529701;8796.780080498038;9157.587908543997;9308.83639545057;9472.479013654316;7958.403119765925;11328.980391764451;13131.212127272394;13471.124376915861;13742;12150.75698486043;12322.137450378388;12661;12745.553905613258;12796.87203127994;11679.766404671689;12013;12108.273503589813;12352.876471235173;12353.505859765508;12100.213486123374;12110.878891210976;12214.755704885674;12237.388130593612;12303.69240768988;8494.197019006522;8897.466152030898;8980.820383592421;9404.76488087776;9464.19554337889;9560.043995600385;11736.237144585575;11838.052955763691;11943.820842687193;12199.329036903055 06:54:11 INFO - PROCESS | 6014 | |3;mootools.html;1506.435086842434;1552.642892134809;1696.550414140253;1843.3363988963963;1877.540002699338;8792.956035219822;9510.381825181359;9525.428474291548;9535.237181025519;9538.38000529965;8045.557494337809;8355.707550235744;8598.0112287087;8666.610002549885;9315.673951411702;2406.3863714752756;2448.400141965221;2448.963265551018;2450.0444826517673;2451.4239153798844;2431.9392015199583;2439.8048156147556;2495.625656151583;2495.8752062396807;2496.0764517128687;2085.259732794859;2335.509542995973;2416.6012607919674;2425.357280320716;2433.2943446400523;2442.7679370459778;2449.2529778417575;2465.149523414419;2471.159805666073;2471.1721573272976;2388.952220955571;2424.2121310574134;2428.3200703802963;2430.805535557151;2433.987830060856;9456.621735130655;9497.477638729868;9533.809323813484;9581.760455988657;9612.423254604746;5301.284326615896;5371.167469042305;5466.316710411199;5545.279113715232;5552.500274975231;3217.48520236761;3311.089450401135;3376.5104059911423;3380.8985730428126;3398.320335932823;1011.2163073617932;1042.671558459083;1059.6715018344262;1071.1216802222182;1086.4079076903106;12606.306653134074;12653.430595623127;12697.619071427873;12706.55527056558;12751.362431878462;4556.498785133633;4641.814327426865;4723.197056500403;4741.312509686076;4789.8323558675975;10644.627438039786;10711.73220669474;10733.946330268298;10749.462526873625;10766.515506802212;5410.675359478444;5429.239906413105;5433.538149257357;5443.646162999393;5443.727813609304;5000.62495312853;5053.772580233898;5055;5100.438951715309;5132.28148059272;4214.473190851144;4260.275753121977;4266.360045993126;4273.1240095780295;4285.5928686774705;1156.9595064172713;1239.3988915376035;1240.5782034108447;1289.7678417884683;1293.2499150492692;906.3383729877227;985.0641890204191;986.4426598971559;1004.4726518577805;1013.9391636501686;6361.141245931833;6436.774712885121;6439.195100612424;6467.450266727381;6722.529422940347;982.5529384130203;1011.4335971855784;1016.3038318751679;1024.3444195714749;1057.5981127171626;4169.207850508394;4451.421315228999;4496.392986946786;4500.572445617661;4533.410656614619;11811.350375729246;11857.288562686093;12299.077569182347;12321.075919306088;12333.50665973351;11795.941020294844;12059.276443413422;12068.87931120695;12075.879241207651;12107.757844843054;8570.357223208284;8582.270507006975;8709.129087091367;8756.430831996027;8776.956115219384;6096.878062438637;6116.3883611638485;6148.815535533941;6183.752649894134;6268.843278918063;10605;10743.355398676105;10746.24776265654;10774.892251077388;10881.292715973437;10834.512446939749;10850.403227822546;10857.239993200716;10997.725056873642;11024;7755.883661745192;7829.29536341732;7910.683572657029;7920.089189743253;8014.599270036591;8769.079246679006;8953.686620968236;8956.820863582821;8964.462132271823;8971;7765.223477652416;7897.368210543028;7921.287084162453;7978.800529986796;8180.181981801772;7788.649510771916;8001.119876813429;8066.314363279187;8104.230098140667;8324.750257492286;7512.436567257369;7539.509931854412;7558.470907036454;7559.962200188964;7580.734674286374;5318.813841515683;5472.042392581203;5474.315710536183;5520.53075488588;5548.556115510669;5402.243685884058;5436.048691478896;5485.369182544058;5499.7250137492165;5519.668819870821 06:54:11 INFO - PROCESS | 6014 | |4;prototype.html;348.252997320747;350.7019033821251;355.46325049175755;357.10901301253375;363.9108418437483;5588.524975377098;5589.972050139749;5590.91613625796;5620.831375058745;5626.156076588513;5494.395616482194;5522.585806064541;5545.833624991256;5552.972235138829;5559.360673522546;3777.9055523611855;4062.9187416251657;4313.956860431403;4336.869893903188;4340.696151269412;4336.934945975813;4342.739435633872;4344.78276086196;4362.760048197348;4370.956290437087;4274.123804620045;4293.570642935697;4318.330658747899;4323.178596066753;4328.134373125372;4042.7372220805555;4205.600467955539;4230.661547076257;4230.725002874834;4239.364095385686;3725.6833169180654;3760.1163726524246;3763.73653844231;3767.171222331083;3777.3578491656485;5277.340332458442;5289.44460831611;5291.3650361956425;5308.309919710451;5318.07505871769;4750.95249047512;4848.660593758438;4850.830220942285;4865.927011094836;4926.4827193144565;5618.353889302742;5651.32184137902;5664.518515926151;5672.234248376499;5685.744141513642;4149.605787450188;4161.897097269226;4163.542010378856;4175.54069052404;4182.623563879265;5722.885542289173;5762.9135562966585;5846.999999999957;5948.791792287249;5995.700214989233;4112.149532710233;4123.340265557556;4237.555056719062;4338.414314067561;4355.651547876162;4388.363687265392;4398.252297109499;4400.207962566768;4402.559744025636;4411.735295882258;4829.054276891094;4950.752462376867;4955.628327875424;4972.5027497249985;4972.651914366032;5700.486956173964;5743.798967036134;5744.926464341641;5767.36558978512;5771.509421699203;1505.7577498563728;1527.3356090100842;1528.456259178238;1529.8678977556751;1531.6783475470277;919.6735159018452;984.3700031979539;986.9309148359546;998.286225348879;1002.0580654185188;4916.778744956486;4920.335754673073;4928.359313289249;4931.950680493221;4940.950590494049;5122.667026643257;5147.510986456281;5158.755941165605;5178.663386879841;5189.792408303626;4598.839040633562;4875.903801086336;4946.5407704727195;4956;4975.776090075955;2753.490604238222;2757.26932362924;2759.227416323433;2768.23873434803;2783.471140483342;5351.973240133852;5371.274877891513;5372.355317361941;5378;5384;5102.540771330598;5145.305383773218;5180.248863914711;5228.607854410934;5311.7078560678765;5321.521063104262;5324.281222035053;5327.866803329948;5341.225522299323;5365.007473617393;5116.820911268237;5182.585393168462;5187.507186817246;5207.27098206259;5240.292560504283;4925.359703238556;5075.654951437946;5085.4406015337545;5112.667676601009;5115.769790359368;5307.115973829235;5793.246877905845;5948.2862056553495;5995.760169593167;6020.789272375621;4310.612044915973;4330.978345108254;4350.390945267602;4369.148016136823;4381.715188512737;5724.542036636975;5952.255968003999;5961.4038596140035;5976.19321391624;5990.341062483036;5690.374058853606;5706.343770466284;5717.970765262126;5745.471992323532;5764.290783331828;5740.39725828782;5746.511546518593;5755.338136114233;5810.273715785527;5825.621334613406;5512.283403157564;5746.856328591652;5754.302480768267;5788.855278618068;5807.283338663344;5842.941570584239;5845.18167456548;5872.383399742966;5893.823185304276;5903.557233207613;5512.944870551243;5675.460831221028;5697.715114244188;5699.487046165699;5705.515031222392 06:54:11 INFO - PROCESS | 6014 | |5;yui.html;795.2365728900255;832.1013305629921;866.3112825303883;921.2261700171854;953.0374321934846;16359.100249486273;17374;17406.04266765329;17409.82590174098;17462.039587822666;15984.920075399607;16066.036037837737;16088.597785055379;16089.034657920532;16102.1948902555;743.5278598090225;879.0857508191473;884.6505630276054;907.0611916666252;912.4479689787682;898.3127907151035;899.1143723432414;901.1214066285385;901.621319045999;902.9322800789935;955.2501286490107;956.3831328792925;960.346964064436;960.5053397500293;961.0965692249298;761.4403413491079;892.7143314139478;942.5711301357866;950.2160717408166;952.8570714392828;697.6651207420466;699.6326928362599;700.0514303119302;700.0689083518907;700.1213477086275;13342.466301347935;13394.933025334909;13419;13464.528741494001;13519.918406527455;14081.436742530286;14287.428502859873;14302;14488.71022579553;14703.92648036753;12751.368624439472;13242.139260948008;13593.728125437534;13958.930205348908;14087.929560352133;4638.9536104638855;4670.276107203389;4699.365585645928;4786.329913812069;4806.951930480685;83881.58059209665;86048;89184;91090.5445472775;93344;12420.254784712946;12567.874321256853;12641.747165056648;12677.936610316889;12759.681007974688;22955.770442295696;23155;23160.65259021116;23184.53630927406;23193.652095218586;8924.598393072327;9156.130167634064;9258.120478554527;9346.953265233631;9374.85937710935;4982.975085124551;5052.115879721034;5128.871778205575;5160.7419629018395;5345.037893179265;4163.167366526707;4294.227039132986;4324.589164029412;4327.783610819446;4334.219840428753;591.9940800592025;692.840646651263;695.7356204642203;699.1295836683362;721.4480922094601;583.6031498581006;754.8716718157926;769.6651956398985;800.9719659811879;809.3161278719473;10364.948175259226;10500.947495262628;10861.945690271497;10966.835497467544;11151.944240278746;661.5137873662854;682.2427105912433;725.4994054102652;752.9510581812274;765.2194761343399;5024.447310795809;5111.396465491819;5154;5202.193659982709;5232.790688372423;104898.4755076235;131649;133771;134981;136649;106185;106572.46713766537;108970;109476;115311.99999999833;93169.53415233016;93854.53072734593;96548.59082852736;96603.99999999859;100384;9247;9887.159591434809;9925.454100024279;9926.305158638826;10087.293889427963;57381.13928290996;58042.41957580539;58061.4193858056;58204.12693809511;59061.70469147627;84544.57727711322;84908;85320.57339713261;85944.57027714822;88330.55834720786;12527.93736031314;12714.809277860655;12841.935790321362;12847.614571562868;13121.671958201121;14510.274486275433;14533.128012319296;14543.49097781615;14729;15502.147381894105;13216.735665286447;14027.649308767363;14083.647908802363;14278.143311401349;14629.63425914318;13656.522021729192;13666.726665466835;13783.173009619053;13850.722985540435;14532;13039.543615973396;13517.729645407233;13574.524891628746;13842.723145536831;14110.929445353118;10523.368597884151;10626.574937002433;10774.24580279373;11145.442727863412;11214.775704486028;10797.460126993776;11079.889201107886;11250.437478126225;11365.204435689753;11437.885621144014 06:54:11 INFO - PROCESS | 6014 | __end_tp_report 06:54:11 INFO - PROCESS | 6014 | __start_cc_report 06:54:11 INFO - PROCESS | 6014 | _x_x_mozilla_cycle_collect,1137 06:54:11 INFO - PROCESS | 6014 | __end_cc_report 06:54:11 INFO - PROCESS | 6014 | __startTimestamp1473342851923__endTimestamp 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | ------- Summary: start ------- 06:54:11 INFO - PROCESS | 6014 | Number of tests: 6 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#0] dojo.html Cycles:165 Average:5509.79 Median:5732.51 stddev:613.47 (10.7%) stddev-sans-first:587.33 06:54:11 INFO - PROCESS | 6014 | Values: 3173.0 3181.0 3218.0 3222.7 3252.7 5485.6 5591.7 5595.7 5605.2 5618.6 5504.4 5560.4 5573.5 5575.4 5589.0 4962.5 4994.2 5001.6 5026.5 5079.9 4964.1 5003.4 5025.0 5031.7 5070.5 5004.0 5080.8 5091.9 5104.8 5109.1 5137.3 5162.0 5163.6 5164.5 5167.0 4893.9 4894.9 4931.2 5060.8 5097.5 5380.7 5394.2 5406.1 5420.9 5445.8 5840.8 5845.8 5846.5 5847.4 5848.3 5720.2 5722.1 5752.8 5778.7 5886.5 5478.3 5478.5 5486.9 5502.7 5506.3 5906.1 5928.5 5929.9 5952.8 5961.2 5663.7 5730.2 5835.7 5904.7 5913.1 5957.2 5958.5 5958.6 5963.2 5986.3 5915.3 5927.9 5935.7 5937.0 5938.1 5713.3 5732.5 5838.6 5856.3 5935.3 4565.2 4595.5 4619.4 4624.0 4629.7 4113.3 4114.6 4118.8 4120.1 4138.2 5438.1 5558.2 5594.3 5696.9 5711.5 5971.0 5976.8 5996.8 6011.2 6025.2 5638.1 5650.9 5654.7 5659.4 5673.0 4993.4 5064.5 5124.3 5164.1 5165.4 6117.1 6121.5 6130.5 6136.1 6138.8 5937.6 5942.2 5949.7 5965.4 5968.3 5921.3 5995.0 6033.7 6156.6 6169.4 5834.1 5838.4 5845.2 5862.5 5877.4 5885.9 5937.9 5947.7 5952.9 5955.4 5617.2 5795.8 5809.9 5818.4 5821.7 5908.1 5915.9 5918.5 5934.3 5944.0 5884.7 5894.6 5894.7 5897.6 5905.0 5721.3 5838.3 5896.7 5922.1 5934.1 5788.9 5789.8 5790.0 5794.7 5813.1 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#1] ext.html Cycles:180 Average:70677.56 Median:41470.59 stddev:153414.04 (369.9%) stddev-sans-first:153754.15 06:54:11 INFO - PROCESS | 6014 | Values: 587.8 626.7 651.5 654.4 668.5 35057.6 46853.0 48318.5 48365.8 48376.8 49493.8 49836.8 49944.5 49956.8 49987.0 8476.4 9242.2 9387.6 9389.8 9396.0 8428.7 8703.9 8704.0 10835.4 11122.3 11256.2 11283.4 11299.4 11327.6 11335.0 11147.8 11231.0 11233.6 11238.2 11241.6 11001.1 11063.6 11067.9 11069.2 11080.7 42649.8 42959.0 43008.8 43033.6 43052.4 3823.6 3871.2 3876.6 4185.5 4531.3 35166.1 37273.0 37356.8 37546.0 37593.0 4814.6 4823.1 4848.4 4877.9 5056.8 383993.0 387544.0 387702.0 389296.0 389966.1 70253.3 70947.6 71189.6 71237.3 71356.6 41381.2 42337.2 42365.6 44779.0 44813.6 20727.2 20898.2 20905.9 20939.4 20940.6 14272.6 15528.5 15619.2 15626.5 15634.1 22181.1 22918.9 23252.3 23840.8 24151.6 8278.0 8311.4 8391.5 8481.3 8700.8 1032.8 1086.3 1128.1 1155.2 1160.1 34995.7 35599.8 35753.6 35890.0 35953.5 1130.8 1209.9 1252.8 1260.6 1270.7 17799.3 18725.6 18742.9 18758.4 18794.5 631524.0 687007.0 692217.0 692703.7 1101283.0 66673.7 67182.3 67304.0 67329.3 67575.0 1360.4 1465.3 1471.4 1498.6 1522.0 31510.0 35884.3 36046.6 36059.6 36084.5 54543.0 55172.0 55178.2 55227.2 55574.7 471573.0 472070.0 473271.0 475430.0 476489.0 28190.6 31805.7 32234.4 33261.5 33348.7 45794.0 51268.0 51608.2 51638.5 52878.7 43046.4 43103.4 43628.0 43753.0 45437.3 43528.1 43964.8 44375.0 44408.3 45875.5 43605.3 44373.6 45213.3 45361.8 47968.0 38309.6 40779.2 40987.4 41560.0 41680.5 30492.8 30514.8 30646.8 31063.4 31275.2 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#2] jquery.html Cycles:180 Average:28684.71 Median:2149.17 stddev:79301.04 (3689.9%) stddev-sans-first:79495.06 06:54:11 INFO - PROCESS | 6014 | Values: 402.1 407.6 420.4 425.6 436.8 19865.1 21025.5 21128.2 21332.4 21420.7 16664.8 19764.0 20080.5 20120.0 20298.2 5328.8 5606.6 5607.9 5612.2 5613.0 5333.3 5390.7 5455.2 5476.6 5537.2 5447.8 5448.2 5451.3 5454.5 5454.6 5330.8 5440.0 5443.8 5540.8 5582.9 5435.8 5444.6 5447.8 5453.9 5454.3 15087.2 15181.8 15277.4 15326.0 15334.3 2349.5 2542.9 2622.6 2667.3 2819.1 10999.9 11209.2 11359.3 11392.6 11472.5 1998.2 2017.6 2029.6 2128.7 2154.6 81657.6 87046.6 89153.1 91230.5 92468.0 10408.6 10903.5 11101.7 11241.4 11248.3 15094.3 15100.8 15113.8 15132.0 15132.8 8744.6 8749.8 8791.0 8970.6 9184.1 9166.3 9178.6 9188.2 9378.0 9405.0 4626.9 4798.3 4994.1 5005.7 5127.7 2021.5 2090.8 2105.0 2116.7 2143.7 716.0 1011.8 1043.2 1138.6 1146.2 19646.0 19911.0 20012.3 20108.2 20914.6 1055.5 1086.8 1093.2 1133.9 1137.0 10747.8 10768.6 11222.0 11244.2 11248.8 473445.0 475343.0 475400.0 487630.0 493861.0 51010.0 59478.7 65898.3 87259.0 99365.5 1031.9 1041.7 1064.8 1065.7 1065.8 17387.2 17903.9 18100.6 18224.5 18252.5 44096.3 44664.6 45090.3 45454.5 47568.0 63203.5 63424.2 63966.0 64681.0 64877.7 8595.7 8796.8 9157.6 9308.8 9472.5 7958.4 11329.0 13131.2 13471.1 13742.0 12150.8 12322.1 12661.0 12745.6 12796.9 11679.8 12013.0 12108.3 12352.9 12353.5 12100.2 12110.9 12214.8 12237.4 12303.7 8494.2 8897.5 8980.8 9404.8 9464.2 9560.0 11736.2 11838.1 11943.8 12199.3 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#3] mootools.html Cycles:180 Average:6115.12 Median:4544.95 stddev:3521.60 (77.5%) stddev-sans-first:3514.45 06:54:11 INFO - PROCESS | 6014 | Values: 1506.4 1552.6 1696.6 1843.3 1877.5 8793.0 9510.4 9525.4 9535.2 9538.4 8045.6 8355.7 8598.0 8666.6 9315.7 2406.4 2448.4 2449.0 2450.0 2451.4 2431.9 2439.8 2495.6 2495.9 2496.1 2085.3 2335.5 2416.6 2425.4 2433.3 2442.8 2449.3 2465.1 2471.2 2471.2 2389.0 2424.2 2428.3 2430.8 2434.0 9456.6 9497.5 9533.8 9581.8 9612.4 5301.3 5371.2 5466.3 5545.3 5552.5 3217.5 3311.1 3376.5 3380.9 3398.3 1011.2 1042.7 1059.7 1071.1 1086.4 12606.3 12653.4 12697.6 12706.6 12751.4 4556.5 4641.8 4723.2 4741.3 4789.8 10644.6 10711.7 10733.9 10749.5 10766.5 5410.7 5429.2 5433.5 5443.6 5443.7 5000.6 5053.8 5055.0 5100.4 5132.3 4214.5 4260.3 4266.4 4273.1 4285.6 1157.0 1239.4 1240.6 1289.8 1293.2 906.3 985.1 986.4 1004.5 1013.9 6361.1 6436.8 6439.2 6467.5 6722.5 982.6 1011.4 1016.3 1024.3 1057.6 4169.2 4451.4 4496.4 4500.6 4533.4 11811.4 11857.3 12299.1 12321.1 12333.5 11795.9 12059.3 12068.9 12075.9 12107.8 8570.4 8582.3 8709.1 8756.4 8777.0 6096.9 6116.4 6148.8 6183.8 6268.8 10605.0 10743.4 10746.2 10774.9 10881.3 10834.5 10850.4 10857.2 10997.7 11024.0 7755.9 7829.3 7910.7 7920.1 8014.6 8769.1 8953.7 8956.8 8964.5 8971.0 7765.2 7897.4 7921.3 7978.8 8180.2 7788.6 8001.1 8066.3 8104.2 8324.8 7512.4 7539.5 7558.5 7560.0 7580.7 5318.8 5472.0 5474.3 5520.5 5548.6 5402.2 5436.0 5485.4 5499.7 5519.7 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#4] prototype.html Cycles:180 Average:4683.88 Median:5153.13 stddev:1342.44 (26.1%) stddev-sans-first:1306.17 06:54:11 INFO - PROCESS | 6014 | Values: 348.3 350.7 355.5 357.1 363.9 5588.5 5590.0 5590.9 5620.8 5626.2 5494.4 5522.6 5545.8 5553.0 5559.4 3777.9 4062.9 4314.0 4336.9 4340.7 4336.9 4342.7 4344.8 4362.8 4371.0 4274.1 4293.6 4318.3 4323.2 4328.1 4042.7 4205.6 4230.7 4230.7 4239.4 3725.7 3760.1 3763.7 3767.2 3777.4 5277.3 5289.4 5291.4 5308.3 5318.1 4751.0 4848.7 4850.8 4865.9 4926.5 5618.4 5651.3 5664.5 5672.2 5685.7 4149.6 4161.9 4163.5 4175.5 4182.6 5722.9 5762.9 5847.0 5948.8 5995.7 4112.1 4123.3 4237.6 4338.4 4355.7 4388.4 4398.3 4400.2 4402.6 4411.7 4829.1 4950.8 4955.6 4972.5 4972.7 5700.5 5743.8 5744.9 5767.4 5771.5 1505.8 1527.3 1528.5 1529.9 1531.7 919.7 984.4 986.9 998.3 1002.1 4916.8 4920.3 4928.4 4932.0 4941.0 5122.7 5147.5 5158.8 5178.7 5189.8 4598.8 4875.9 4946.5 4956.0 4975.8 2753.5 2757.3 2759.2 2768.2 2783.5 5352.0 5371.3 5372.4 5378.0 5384.0 5102.5 5145.3 5180.2 5228.6 5311.7 5321.5 5324.3 5327.9 5341.2 5365.0 5116.8 5182.6 5187.5 5207.3 5240.3 4925.4 5075.7 5085.4 5112.7 5115.8 5307.1 5793.2 5948.3 5995.8 6020.8 4310.6 4331.0 4350.4 4369.1 4381.7 5724.5 5952.3 5961.4 5976.2 5990.3 5690.4 5706.3 5718.0 5745.5 5764.3 5740.4 5746.5 5755.3 5810.3 5825.6 5512.3 5746.9 5754.3 5788.9 5807.3 5842.9 5845.2 5872.4 5893.8 5903.6 5512.9 5675.5 5697.7 5699.5 5705.5 06:54:11 INFO - PROCESS | 6014 | 06:54:11 INFO - PROCESS | 6014 | [#5] yui.html Cycles:180 Average:22900.46 Median:4331.00 stddev:33838.21 (781.3%) stddev-sans-first:33892.43 06:54:11 INFO - PROCESS | 6014 | Values: 795.2 832.1 866.3 921.2 953.0 16359.1 17374.0 17406.0 17409.8 17462.0 15984.9 16066.0 16088.6 16089.0 16102.2 743.5 879.1 884.7 907.1 912.4 898.3 899.1 901.1 901.6 902.9 955.3 956.4 960.3 960.5 961.1 761.4 892.7 942.6 950.2 952.9 697.7 699.6 700.1 700.1 700.1 13342.5 13394.9 13419.0 13464.5 13519.9 14081.4 14287.4 14302.0 14488.7 14703.9 12751.4 13242.1 13593.7 13958.9 14087.9 4639.0 4670.3 4699.4 4786.3 4807.0 83881.6 86048.0 89184.0 91090.5 93344.0 12420.3 12567.9 12641.7 12677.9 12759.7 22955.8 23155.0 23160.7 23184.5 23193.7 8924.6 9156.1 9258.1 9347.0 9374.9 4983.0 5052.1 5128.9 5160.7 5345.0 4163.2 4294.2 4324.6 4327.8 4334.2 592.0 692.8 695.7 699.1 721.4 583.6 754.9 769.7 801.0 809.3 10364.9 10500.9 10861.9 10966.8 11151.9 661.5 682.2 725.5 753.0 765.2 5024.4 5111.4 5154.0 5202.2 5232.8 104898.5 131649.0 133771.0 134981.0 136649.0 106185.0 106572.5 108970.0 109476.0 115312.0 93169.5 93854.5 96548.6 96604.0 100384.0 9247.0 9887.2 9925.5 9926.3 10087.3 57381.1 58042.4 58061.4 58204.1 59061.7 84544.6 84908.0 85320.6 85944.6 88330.6 12527.9 12714.8 12841.9 12847.6 13121.7 14510.3 14533.1 14543.5 14729.0 15502.1 13216.7 14027.6 14083.6 14278.1 14629.6 13656.5 13666.7 13783.2 13850.7 14532.0 13039.5 13517.7 13574.5 13842.7 14110.9 10523.4 10626.6 10774.2 11145.4 11214.8 10797.5 11079.9 11250.4 11365.2 11437.9 06:54:11 INFO - PROCESS | 6014 | -------- Summary: end -------- 06:54:11 INFO - PROCESS | 6014 | 06:54:12 INFO - TEST-INFO | 6014: exit 0 06:54:12 INFO - TEST-OK | dromaeo_css | took 1112296ms 06:54:12 INFO - TEST-START | kraken 06:54:12 INFO - operating with platform_type : linux_ 06:54:12 INFO - Initialising browser for kraken test... 06:54:12 INFO - TEST-INFO | started process 6222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNTgfgS/profile http://localhost:42680/getInfo.html) 06:54:16 INFO - PROCESS | 6222 | __metrics Screen width/height:1600/1200 06:54:16 INFO - PROCESS | 6222 | colorDepth:24 06:54:16 INFO - PROCESS | 6222 | Browser inner width/height: 1024/697 06:54:16 INFO - PROCESS | 6222 | __metrics 06:54:17 INFO - PROCESS | 6222 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:54:17 INFO - PROCESS | 6222 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:54:17 INFO - TEST-INFO | 6222: exit 0 06:54:17 INFO - Browser initialized. 06:54:17 INFO - Running cycle 1/1 for kraken test... 06:54:17 INFO - TEST-INFO | started process 6312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNTgfgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:54:29 INFO - PROCESS | 6312 | RSS: Main: 145997824 06:54:29 INFO - PROCESS | 6312 | 06:54:32 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42680/tests/kraken/driver.html?testName=audio-beat-detection) 06:54:33 INFO - PROCESS | 6312 | RSS: Main: 200830976 06:54:33 INFO - PROCESS | 6312 | 06:54:35 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42680/tests/kraken/driver.html?testName=audio-dft) 06:54:36 INFO - PROCESS | 6312 | RSS: Main: 185270272 06:54:36 INFO - PROCESS | 6312 | 06:54:39 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42680/tests/kraken/driver.html?testName=audio-fft) 06:54:39 INFO - PROCESS | 6312 | RSS: Main: 275849216 06:54:39 INFO - PROCESS | 6312 | 06:54:41 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42680/tests/kraken/driver.html?testName=audio-oscillator) 06:54:42 INFO - PROCESS | 6312 | RSS: Main: 182280192 06:54:42 INFO - PROCESS | 6312 | 06:54:44 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42680/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:54:45 INFO - PROCESS | 6312 | RSS: Main: 180797440 06:54:45 INFO - PROCESS | 6312 | 06:54:48 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42680/tests/kraken/driver.html?testName=imaging-darkroom) 06:54:48 INFO - PROCESS | 6312 | RSS: Main: 216567808 06:54:48 INFO - PROCESS | 6312 | 06:54:51 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42680/tests/kraken/driver.html?testName=imaging-desaturate) 06:54:52 INFO - PROCESS | 6312 | RSS: Main: 215810048 06:54:52 INFO - PROCESS | 6312 | 06:54:55 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42680/tests/kraken/driver.html?testName=json-parse-financial) 06:54:55 INFO - PROCESS | 6312 | RSS: Main: 216047616 06:54:55 INFO - PROCESS | 6312 | 06:54:57 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42680/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:54:58 INFO - PROCESS | 6312 | RSS: Main: 178200576 06:54:58 INFO - PROCESS | 6312 | 06:54:59 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:55:00 INFO - PROCESS | 6312 | RSS: Main: 184631296 06:55:00 INFO - PROCESS | 6312 | 06:55:02 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:55:03 INFO - PROCESS | 6312 | RSS: Main: 187535360 06:55:03 INFO - PROCESS | 6312 | 06:55:05 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:55:06 INFO - PROCESS | 6312 | RSS: Main: 180436992 06:55:06 INFO - PROCESS | 6312 | 06:55:08 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:55:09 INFO - PROCESS | 6312 | RSS: Main: 185110528 06:55:09 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | Cycle 1(1): loaded http://localhost:42680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42680/tests/kraken/driver.html?testName=ai-astar) 06:55:11 INFO - PROCESS | 6312 | RSS: Main: 235835392 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | __start_tp_report 06:55:11 INFO - PROCESS | 6312 | _x_x_mozilla_page_load 06:55:11 INFO - PROCESS | 6312 | _x_x_mozilla_page_load_details 06:55:11 INFO - PROCESS | 6312 | |i|pagename|runs| 06:55:11 INFO - PROCESS | 6312 | |0;ai-astar;111;109;122;108;108;109;109;111;112;111 06:55:11 INFO - PROCESS | 6312 | |1;audio-beat-detection;147;116;148;172;115;148;146;118;178;115 06:55:11 INFO - PROCESS | 6312 | |2;audio-dft;161;160;160;160;160;160;159;161;161;159 06:55:11 INFO - PROCESS | 6312 | |3;audio-fft;74;76;75;185;76;76;74;75;166;76 06:55:11 INFO - PROCESS | 6312 | |4;audio-oscillator;85;84;155;83;83;83;133;84;84;148 06:55:11 INFO - PROCESS | 6312 | |5;imaging-gaussian-blur;118;118;117;118;117;116;117;117;117;116 06:55:11 INFO - PROCESS | 6312 | |6;imaging-darkroom;146;146;146;145;146;146;145;146;146;147 06:55:11 INFO - PROCESS | 6312 | |7;imaging-desaturate;94;95;95;94;95;95;95;95;95;94 06:55:11 INFO - PROCESS | 6312 | |8;json-parse-financial;51;52;55;51;50;51;52;52;51;51 06:55:11 INFO - PROCESS | 6312 | |9;json-stringify-tinderbox;59;69;60;59;60;60;60;59;60;60 06:55:11 INFO - PROCESS | 6312 | |10;stanford-crypto-aes;73;73;73;82;76;76;73;78;72;73 06:55:11 INFO - PROCESS | 6312 | |11;stanford-crypto-ccm;132;119;117;125;129;124;115;126;192;126 06:55:11 INFO - PROCESS | 6312 | |12;stanford-crypto-pbkdf2;153;153;151;150;149;150;148;151;159;151 06:55:11 INFO - PROCESS | 6312 | |13;stanford-crypto-sha256-iterative;62;61;62;61;61;61;61;60;61;60 06:55:11 INFO - PROCESS | 6312 | __end_tp_report 06:55:11 INFO - PROCESS | 6312 | __start_cc_report 06:55:11 INFO - PROCESS | 6312 | _x_x_mozilla_cycle_collect,1715 06:55:11 INFO - PROCESS | 6312 | __end_cc_report 06:55:11 INFO - PROCESS | 6312 | __startTimestamp1473342911347__endTimestamp 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | ------- Summary: start ------- 06:55:11 INFO - PROCESS | 6312 | Number of tests: 14 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:4.11 (3.7%) stddev-sans-first:4.36 06:55:11 INFO - PROCESS | 6312 | Values: 111.0 109.0 122.0 108.0 108.0 109.0 109.0 111.0 112.0 111.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:147.50 stddev:23.55 (16.0%) stddev-sans-first:24.85 06:55:11 INFO - PROCESS | 6312 | Values: 147.0 116.0 148.0 172.0 115.0 148.0 146.0 118.0 178.0 115.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 06:55:11 INFO - PROCESS | 6312 | Values: 161.0 160.0 160.0 160.0 160.0 160.0 159.0 161.0 161.0 159.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#3] audio-fft Cycles:10 Average:95.30 Median:75.50 stddev:42.51 (56.3%) stddev-sans-first:44.39 06:55:11 INFO - PROCESS | 6312 | Values: 74.0 76.0 75.0 185.0 76.0 76.0 74.0 75.0 166.0 76.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.50 stddev:30.24 (36.2%) stddev-sans-first:31.43 06:55:11 INFO - PROCESS | 6312 | Values: 85.0 84.0 155.0 83.0 83.0 83.0 133.0 84.0 84.0 148.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 06:55:11 INFO - PROCESS | 6312 | Values: 118.0 118.0 117.0 118.0 117.0 116.0 117.0 117.0 117.0 116.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:55:11 INFO - PROCESS | 6312 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 147.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 06:55:11 INFO - PROCESS | 6312 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:1.35 (2.6%) stddev-sans-first:1.41 06:55:11 INFO - PROCESS | 6312 | Values: 51.0 52.0 55.0 51.0 50.0 51.0 52.0 52.0 51.0 51.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.00 stddev:2.99 (5.0%) stddev-sans-first:3.11 06:55:11 INFO - PROCESS | 6312 | Values: 59.0 69.0 60.0 59.0 60.0 60.0 60.0 59.0 60.0 60.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:3.14 (4.2%) stddev-sans-first:3.26 06:55:11 INFO - PROCESS | 6312 | Values: 73.0 73.0 73.0 82.0 76.0 76.0 73.0 78.0 72.0 73.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:126.00 stddev:22.25 (17.7%) stddev-sans-first:23.59 06:55:11 INFO - PROCESS | 6312 | Values: 132.0 119.0 117.0 125.0 129.0 124.0 115.0 126.0 192.0 126.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:151.00 stddev:3.06 (2.0%) stddev-sans-first:3.20 06:55:11 INFO - PROCESS | 6312 | Values: 153.0 153.0 151.0 150.0 149.0 150.0 148.0 151.0 159.0 151.0 06:55:11 INFO - PROCESS | 6312 | 06:55:11 INFO - PROCESS | 6312 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 06:55:11 INFO - PROCESS | 6312 | Values: 62.0 61.0 62.0 61.0 61.0 61.0 61.0 60.0 61.0 60.0 06:55:11 INFO - PROCESS | 6312 | -------- Summary: end -------- 06:55:11 INFO - PROCESS | 6312 | 06:55:12 INFO - TEST-INFO | 6312: exit 0 06:55:12 INFO - TEST-OK | kraken | took 59816ms 06:55:12 INFO - SUITE-END | took 1172s 06:55:12 INFO - Completed test suite (00:19:32) 06:55:12 INFO - javascript benchmark 06:55:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3172.952405713914, 3181.0, 3217.951730724038, 3222.693844084811, 3252.723518500927, 5485.588580856437, 5591.664500129985, 5595.664260144384, 5605.1872478490595, 5618.634788738735, 5504.394516603181, 5560.4161563035905, 5573.526250268732, 5575.442455754423, 5589.0, 4962.528559786814, 4994.150994330972, 5001.59987201024, 5026.547610715035, 5079.898402031976, 4964.056829202458, 5003.424606170301, 5025.04524140414, 5031.723255220962, 5070.467600901908, 5003.974980125113, 5080.771365288552, 5091.9236211456855, 5104.821331253407, 5109.105906466372, 5137.280780690707, 5162.019216348882, 5163.63854530183, 5164.509371609691, 5167.044096842059, 4893.853184404438, 4894.926576101326, 4931.235658472942, 5060.84817455477, 5097.49025097491, 5380.704061276629, 5394.244805727201, 5406.053940560386, 5420.945790542123, 5445.782168713246, 5840.8026354597205, 5845.766169353221, 5846.532277417797, 5847.356790753014, 5848.298204215522, 5720.22776925114, 5722.141678748179, 5752.7986520472, 5778.682172480512, 5886.499647529965, 5478.342598888119, 5478.506934375925, 5486.862828429242, 5502.724863756836, 5506.311711036121, 5906.143609176645, 5928.525717942554, 5929.881402371929, 5952.791652292236, 5961.195238642815, 5663.7451314690115, 5730.226419433407, 5835.708214589254, 5904.704764761745, 5913.113033044991, 5957.195778569924, 5958.523318134538, 5958.612690175125, 5963.224780778558, 5986.341502434729, 5915.290165180119, 5927.9407205928255, 5935.73289201987, 5937.020391635332, 5938.079597662369, 5713.257276554022, 5732.512736417368, 5838.5621078418435, 5856.326522449887, 5935.2877654681715, 4565.178267911808, 4595.471520775087, 4619.376384188159, 4624.028953919715, 4629.675922685447, 4113.259613269581, 4114.567970363129, 4118.835246590163, 4120.052387950788, 4138.193052354762, 5438.129899216186, 5558.221848941152, 5594.272744543265, 5696.857578560486, 5711.485966263056, 5971.0, 5976.760929562769, 5996.820095397145, 6011.158437818709, 6025.216721826135, 5638.126090455986, 5650.886982260332, 5654.660720356792, 5659.377468478464, 5673.035583950738, 4993.375828021497, 5064.468230835783, 5124.30821839047, 5164.096283150434, 5165.354330708661, 6117.143599896018, 6121.4529735759925, 6130.47890929276, 6136.090214303957, 6138.7869253627705, 5937.554683398849, 5942.168096466585, 5949.672767997628, 5965.433283838029, 5968.313643931004, 5921.289445266596, 5995.0407934731975, 6033.698315084141, 6156.5998210116195, 6169.413905678954, 5834.095715164156, 5838.357780644041, 5845.240118784531, 5862.530997520103, 5877.382874798255, 5885.882282354243, 5937.851553711192, 5947.702614869326, 5952.910706339322, 5955.374685657944, 5617.185508101385, 5795.797147099831, 5809.912851307318, 5818.359980402237, 5821.738021789114, 5908.143319218775, 5915.881682366415, 5918.526517878646, 5934.317553481233, 5944.019236825875, 5884.735186916513, 5894.616849904914, 5894.6757928314355, 5897.616654917417, 5904.996150654313, 5721.284839395075, 5838.32859221195, 5896.734646940984, 5922.052471604523, 5934.139549765347, 5788.8552786179, 5789.768409263583, 5790.0, 5794.652320860761, 5813.09896965971], "value": 5469.054051520663, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.806024012076, 626.7179769103903, 651.4527796650813, 654.417568364156, 668.4785867023724, 35057.649423505754, 46853.0, 48318.51681483184, 48365.758171209185, 48376.758116209414, 49493.752531237296, 49836.75081624587, 49944.50055499444, 49956.75021624896, 49987.0, 8476.364272679573, 9242.214411775007, 9387.62449502019, 9389.81220375592, 9395.953020234889, 8428.704995325164, 8703.869441958375, 8704.0, 10835.404052777094, 11122.277051991636, 11256.212065155401, 11283.37941413226, 11299.378534180616, 11327.603533876316, 11335.0, 11147.777044459106, 11231.045361144312, 11233.550657973672, 11238.213325067249, 11241.550337986471, 11001.064909482704, 11063.557457701681, 11067.889321106766, 11069.169812264032, 11080.722981925435, 42649.78675106636, 42959.0, 43008.78495607502, 43033.56966430327, 43052.354214686806, 3823.560290566593, 3871.2064026874423, 3876.6317199865966, 4185.518665353493, 4531.3429552715, 35166.120846978774, 37273.0, 37356.81321593402, 37546.0, 37593.0, 4814.614830813562, 4823.107725070838, 4848.369711937461, 4877.926831097537, 5056.797728090872, 383993.0, 387544.0, 387702.0, 389296.0, 389966.0501697502, 70253.2974670257, 70947.64526177336, 71189.64405177941, 71237.2876271241, 71356.64321678359, 41381.1723765523, 42337.15325693469, 42365.57634423678, 44779.0, 44813.55186448159, 20727.170913163605, 20898.164073437198, 20905.89547052255, 20939.37181884546, 20940.58118837615, 14272.643183920485, 15528.5341439757, 15619.219039048003, 15626.45307414235, 15634.140122293384, 22181.11275548992, 22918.885405572866, 23252.3024309271, 23840.761592383853, 24151.63772543413, 8277.963730828747, 8311.418200726011, 8391.454555453876, 8481.321494280442, 8700.78248043804, 1032.8244198486275, 1086.3101930274174, 1128.0834445597936, 1155.185594156118, 1160.0893298760432, 34995.72527785686, 35599.82200088983, 35753.642463575554, 35890.0, 35953.460698089024, 1130.8473356096986, 1209.8911098001222, 1252.7557126360455, 1260.5966090851023, 1270.675977625718, 17799.288028478975, 18725.625487490175, 18742.906285468485, 18758.437246882615, 18794.53013674669, 631524.0, 687007.0, 692217.0, 692703.6824079338, 1101283.0, 66673.66663166654, 67182.32817671761, 67304.0, 67329.32670673328, 67575.0, 1360.4490181476517, 1465.3039806092022, 1471.3526048538608, 1498.6028702393874, 1522.0335087219696, 31510.0, 35884.28231435409, 36046.63953360433, 36059.63940360563, 36084.458733118496, 54543.0, 55172.0, 55178.172327414315, 55227.171592425344, 55574.72212638911, 471573.0, 472070.0, 473271.0, 475430.0, 476489.0, 28190.577141342485, 31805.681943180272, 32234.35531289314, 33261.50107748434, 33348.66651333456, 45794.0, 51268.0, 51608.225876611126, 51638.48361516487, 52878.73560632172, 43046.35430468608, 43103.35344969765, 43628.0, 43753.0, 45437.31844022409, 43528.12943741044, 43964.78017609892, 44375.0, 44408.33387499126, 45875.541244588465, 43605.345919811865, 44373.55626443694, 45213.321800172365, 45361.773191133834, 47968.0, 38309.61690383172, 40779.1844163121, 40987.38518922278, 41560.0, 41680.541181058514, 30492.84753576218, 30514.84742576362, 30646.757218674138, 31063.378732425677, 31275.218119547193], "value": 22477.345693970234, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.1093278388369, 407.559835377792, 420.3925327901183, 425.56592275878603, 436.80562149843314, 19865.106070226837, 21025.47436314093, 21128.154873805066, 21332.36002919915, 21420.678689819644, 16664.75002874958, 19764.0, 20080.49798755032, 20120.0, 20298.18807247712, 5328.8401347959625, 5606.607537472379, 5607.943920560803, 5612.15817627355, 5612.971935140318, 5333.333333333333, 5390.703511306877, 5455.18172274158, 5476.616636835423, 5537.197106419567, 5447.836564903039, 5448.182772584105, 5451.2640793492965, 5454.481824226692, 5454.563634909197, 5330.813421530247, 5439.972800135994, 5443.782248710047, 5540.778368865241, 5582.888342233173, 5435.782568697247, 5444.6188766786345, 5447.782088716446, 5453.945460545384, 5454.34547854256, 15087.24563771807, 15181.848181518153, 15277.38890444392, 15326.0, 15334.309956051893, 2349.4713689419914, 2542.8855701493476, 2622.5803871380485, 2667.2665017120253, 2819.1401622505127, 10999.890001099966, 11209.159313051554, 11359.261647992855, 11392.601258955896, 11472.4837382318, 1998.1907327532372, 2017.6267390532798, 2029.5839352932614, 2128.70198172256, 2154.6121698094335, 81657.59171204166, 87046.56476717639, 89153.10846891513, 91230.543847281, 92468.0, 10408.635697750542, 10903.509342079626, 11101.666949991513, 11241.381724005258, 11248.300407902045, 15094.320755566026, 15100.773488397683, 15113.773293400387, 15132.0, 15132.848671513364, 8744.562771861509, 8749.825003499895, 8791.0, 8970.551472426352, 9184.081591840762, 9166.312526560534, 9178.586963586655, 9188.1730644241, 9377.953110234404, 9404.95297523508, 4626.86119416418, 4798.304245884327, 4994.076095922266, 5005.6996580205305, 5127.717975511383, 2021.4643119573325, 2090.770015298316, 2104.9579008419746, 2116.6719158530454, 2143.720108234404, 715.9565501501094, 1011.8431643095184, 1043.1811028342715, 1138.5673444091192, 1146.2320245435578, 19646.0, 19911.0, 20012.299569515, 20108.195672173242, 20914.581708365746, 1055.472263868066, 1086.8369744538381, 1093.1637297467444, 1133.8922802333766, 1137.0448822988728, 10747.83878241827, 10768.623098191525, 11222.0, 11244.212905096729, 11248.831267530994, 473445.0, 475343.0, 475400.0, 487630.0, 493861.0, 51010.0, 59478.70260648755, 65898.34101658923, 87259.0, 99365.50317248367, 1031.896810318977, 1041.7083216699336, 1064.7923654887377, 1065.6962765611918, 1065.8454524093963, 17387.21757520889, 17903.910480447514, 18100.637987240443, 18224.4532664015, 18252.543686407946, 44096.33855492106, 44664.55335446604, 45090.323645146, 45454.545454545034, 47568.0, 63203.5238061911, 63424.19454832725, 63966.0, 64681.0, 64877.67561162164, 8595.699150529701, 8796.780080498038, 9157.587908543997, 9308.83639545057, 9472.479013654316, 7958.403119765925, 11328.980391764451, 13131.212127272394, 13471.124376915861, 13742.0, 12150.75698486043, 12322.137450378388, 12661.0, 12745.553905613258, 12796.87203127994, 11679.766404671689, 12013.0, 12108.273503589813, 12352.876471235173, 12353.505859765508, 12100.213486123374, 12110.878891210976, 12214.755704885674, 12237.388130593612, 12303.69240768988, 8494.197019006522, 8897.466152030898, 8980.820383592421, 9404.76488087776, 9464.19554337889, 9560.043995600385, 11736.237144585575, 11838.052955763691, 11943.820842687193, 12199.329036903055], "value": 9366.669683526809, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1506.435086842434, 1552.642892134809, 1696.550414140253, 1843.3363988963963, 1877.540002699338, 8792.956035219822, 9510.381825181359, 9525.428474291548, 9535.237181025519, 9538.38000529965, 8045.557494337809, 8355.707550235744, 8598.0112287087, 8666.610002549885, 9315.673951411702, 2406.3863714752756, 2448.400141965221, 2448.963265551018, 2450.0444826517673, 2451.4239153798844, 2431.9392015199583, 2439.8048156147556, 2495.625656151583, 2495.8752062396807, 2496.0764517128687, 2085.259732794859, 2335.509542995973, 2416.6012607919674, 2425.357280320716, 2433.2943446400523, 2442.7679370459778, 2449.2529778417575, 2465.149523414419, 2471.159805666073, 2471.1721573272976, 2388.952220955571, 2424.2121310574134, 2428.3200703802963, 2430.805535557151, 2433.987830060856, 9456.621735130655, 9497.477638729868, 9533.809323813484, 9581.760455988657, 9612.423254604746, 5301.284326615896, 5371.167469042305, 5466.316710411199, 5545.279113715232, 5552.500274975231, 3217.48520236761, 3311.089450401135, 3376.5104059911423, 3380.8985730428126, 3398.320335932823, 1011.2163073617932, 1042.671558459083, 1059.6715018344262, 1071.1216802222182, 1086.4079076903106, 12606.306653134074, 12653.430595623127, 12697.619071427873, 12706.55527056558, 12751.362431878462, 4556.498785133633, 4641.814327426865, 4723.197056500403, 4741.312509686076, 4789.8323558675975, 10644.627438039786, 10711.73220669474, 10733.946330268298, 10749.462526873625, 10766.515506802212, 5410.675359478444, 5429.239906413105, 5433.538149257357, 5443.646162999393, 5443.727813609304, 5000.62495312853, 5053.772580233898, 5055.0, 5100.438951715309, 5132.28148059272, 4214.473190851144, 4260.275753121977, 4266.360045993126, 4273.1240095780295, 4285.5928686774705, 1156.9595064172713, 1239.3988915376035, 1240.5782034108447, 1289.7678417884683, 1293.2499150492692, 906.3383729877227, 985.0641890204191, 986.4426598971559, 1004.4726518577805, 1013.9391636501686, 6361.141245931833, 6436.774712885121, 6439.195100612424, 6467.450266727381, 6722.529422940347, 982.5529384130203, 1011.4335971855784, 1016.3038318751679, 1024.3444195714749, 1057.5981127171626, 4169.207850508394, 4451.421315228999, 4496.392986946786, 4500.572445617661, 4533.410656614619, 11811.350375729246, 11857.288562686093, 12299.077569182347, 12321.075919306088, 12333.50665973351, 11795.941020294844, 12059.276443413422, 12068.87931120695, 12075.879241207651, 12107.757844843054, 8570.357223208284, 8582.270507006975, 8709.129087091367, 8756.430831996027, 8776.956115219384, 6096.878062438637, 6116.3883611638485, 6148.815535533941, 6183.752649894134, 6268.843278918063, 10605.0, 10743.355398676105, 10746.24776265654, 10774.892251077388, 10881.292715973437, 10834.512446939749, 10850.403227822546, 10857.239993200716, 10997.725056873642, 11024.0, 7755.883661745192, 7829.29536341732, 7910.683572657029, 7920.089189743253, 8014.599270036591, 8769.079246679006, 8953.686620968236, 8956.820863582821, 8964.462132271823, 8971.0, 7765.223477652416, 7897.368210543028, 7921.287084162453, 7978.800529986796, 8180.181981801772, 7788.649510771916, 8001.119876813429, 8066.314363279187, 8104.230098140667, 8324.750257492286, 7512.436567257369, 7539.509931854412, 7558.470907036454, 7559.962200188964, 7580.734674286374, 5318.813841515683, 5472.042392581203, 5474.315710536183, 5520.53075488588, 5548.556115510669, 5402.243685884058, 5436.048691478896, 5485.369182544058, 5499.7250137492165, 5519.668819870821], "value": 4859.737644772159, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.252997320747, 350.7019033821251, 355.46325049175755, 357.10901301253375, 363.9108418437483, 5588.524975377098, 5589.972050139749, 5590.91613625796, 5620.831375058745, 5626.156076588513, 5494.395616482194, 5522.585806064541, 5545.833624991256, 5552.972235138829, 5559.360673522546, 3777.9055523611855, 4062.9187416251657, 4313.956860431403, 4336.869893903188, 4340.696151269412, 4336.934945975813, 4342.739435633872, 4344.78276086196, 4362.760048197348, 4370.956290437087, 4274.123804620045, 4293.570642935697, 4318.330658747899, 4323.178596066753, 4328.134373125372, 4042.7372220805555, 4205.600467955539, 4230.661547076257, 4230.725002874834, 4239.364095385686, 3725.6833169180654, 3760.1163726524246, 3763.73653844231, 3767.171222331083, 3777.3578491656485, 5277.340332458442, 5289.44460831611, 5291.3650361956425, 5308.309919710451, 5318.07505871769, 4750.95249047512, 4848.660593758438, 4850.830220942285, 4865.927011094836, 4926.4827193144565, 5618.353889302742, 5651.32184137902, 5664.518515926151, 5672.234248376499, 5685.744141513642, 4149.605787450188, 4161.897097269226, 4163.542010378856, 4175.54069052404, 4182.623563879265, 5722.885542289173, 5762.9135562966585, 5846.999999999957, 5948.791792287249, 5995.700214989233, 4112.149532710233, 4123.340265557556, 4237.555056719062, 4338.414314067561, 4355.651547876162, 4388.363687265392, 4398.252297109499, 4400.207962566768, 4402.559744025636, 4411.735295882258, 4829.054276891094, 4950.752462376867, 4955.628327875424, 4972.5027497249985, 4972.651914366032, 5700.486956173964, 5743.798967036134, 5744.926464341641, 5767.36558978512, 5771.509421699203, 1505.7577498563728, 1527.3356090100842, 1528.456259178238, 1529.8678977556751, 1531.6783475470277, 919.6735159018452, 984.3700031979539, 986.9309148359546, 998.286225348879, 1002.0580654185188, 4916.778744956486, 4920.335754673073, 4928.359313289249, 4931.950680493221, 4940.950590494049, 5122.667026643257, 5147.510986456281, 5158.755941165605, 5178.663386879841, 5189.792408303626, 4598.839040633562, 4875.903801086336, 4946.5407704727195, 4956.0, 4975.776090075955, 2753.490604238222, 2757.26932362924, 2759.227416323433, 2768.23873434803, 2783.471140483342, 5351.973240133852, 5371.274877891513, 5372.355317361941, 5378.0, 5384.0, 5102.540771330598, 5145.305383773218, 5180.248863914711, 5228.607854410934, 5311.7078560678765, 5321.521063104262, 5324.281222035053, 5327.866803329948, 5341.225522299323, 5365.007473617393, 5116.820911268237, 5182.585393168462, 5187.507186817246, 5207.27098206259, 5240.292560504283, 4925.359703238556, 5075.654951437946, 5085.4406015337545, 5112.667676601009, 5115.769790359368, 5307.115973829235, 5793.246877905845, 5948.2862056553495, 5995.760169593167, 6020.789272375621, 4310.612044915973, 4330.978345108254, 4350.390945267602, 4369.148016136823, 4381.715188512737, 5724.542036636975, 5952.255968003999, 5961.4038596140035, 5976.19321391624, 5990.341062483036, 5690.374058853606, 5706.343770466284, 5717.970765262126, 5745.471992323532, 5764.290783331828, 5740.39725828782, 5746.511546518593, 5755.338136114233, 5810.273715785527, 5825.621334613406, 5512.283403157564, 5746.856328591652, 5754.302480768267, 5788.855278618068, 5807.283338663344, 5842.941570584239, 5845.18167456548, 5872.383399742966, 5893.823185304276, 5903.557233207613, 5512.944870551243, 5675.460831221028, 5697.715114244188, 5699.487046165699, 5705.515031222392], "value": 4265.575357250484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.2365728900255, 832.1013305629921, 866.3112825303883, 921.2261700171854, 953.0374321934846, 16359.100249486273, 17374.0, 17406.04266765329, 17409.82590174098, 17462.039587822666, 15984.920075399607, 16066.036037837737, 16088.597785055379, 16089.034657920532, 16102.1948902555, 743.5278598090225, 879.0857508191473, 884.6505630276054, 907.0611916666252, 912.4479689787682, 898.3127907151035, 899.1143723432414, 901.1214066285385, 901.621319045999, 902.9322800789935, 955.2501286490107, 956.3831328792925, 960.346964064436, 960.5053397500293, 961.0965692249298, 761.4403413491079, 892.7143314139478, 942.5711301357866, 950.2160717408166, 952.8570714392828, 697.6651207420466, 699.6326928362599, 700.0514303119302, 700.0689083518907, 700.1213477086275, 13342.466301347935, 13394.933025334909, 13419.0, 13464.528741494001, 13519.918406527455, 14081.436742530286, 14287.428502859873, 14302.0, 14488.71022579553, 14703.92648036753, 12751.368624439472, 13242.139260948008, 13593.728125437534, 13958.930205348908, 14087.929560352133, 4638.9536104638855, 4670.276107203389, 4699.365585645928, 4786.329913812069, 4806.951930480685, 83881.58059209665, 86048.0, 89184.0, 91090.5445472775, 93344.0, 12420.254784712946, 12567.874321256853, 12641.747165056648, 12677.936610316889, 12759.681007974688, 22955.770442295696, 23155.0, 23160.65259021116, 23184.53630927406, 23193.652095218586, 8924.598393072327, 9156.130167634064, 9258.120478554527, 9346.953265233631, 9374.85937710935, 4982.975085124551, 5052.115879721034, 5128.871778205575, 5160.7419629018395, 5345.037893179265, 4163.167366526707, 4294.227039132986, 4324.589164029412, 4327.783610819446, 4334.219840428753, 591.9940800592025, 692.840646651263, 695.7356204642203, 699.1295836683362, 721.4480922094601, 583.6031498581006, 754.8716718157926, 769.6651956398985, 800.9719659811879, 809.3161278719473, 10364.948175259226, 10500.947495262628, 10861.945690271497, 10966.835497467544, 11151.944240278746, 661.5137873662854, 682.2427105912433, 725.4994054102652, 752.9510581812274, 765.2194761343399, 5024.447310795809, 5111.396465491819, 5154.0, 5202.193659982709, 5232.790688372423, 104898.4755076235, 131649.0, 133771.0, 134981.0, 136649.0, 106185.0, 106572.46713766537, 108970.0, 109476.0, 115311.99999999833, 93169.53415233016, 93854.53072734593, 96548.59082852736, 96603.99999999859, 100384.0, 9247.0, 9887.159591434809, 9925.454100024279, 9926.305158638826, 10087.293889427963, 57381.13928290996, 58042.41957580539, 58061.4193858056, 58204.12693809511, 59061.70469147627, 84544.57727711322, 84908.0, 85320.57339713261, 85944.57027714822, 88330.55834720786, 12527.93736031314, 12714.809277860655, 12841.935790321362, 12847.614571562868, 13121.671958201121, 14510.274486275433, 14533.128012319296, 14543.49097781615, 14729.0, 15502.147381894105, 13216.735665286447, 14027.649308767363, 14083.647908802363, 14278.143311401349, 14629.63425914318, 13656.522021729192, 13666.726665466835, 13783.173009619053, 13850.722985540435, 14532.0, 13039.543615973396, 13517.729645407233, 13574.524891628746, 13842.723145536831, 14110.929445353118, 10523.368597884151, 10626.574937002433, 10774.24580279373, 11145.442727863412, 11214.775704486028, 10797.460126993776, 11079.889201107886, 11250.437478126225, 11365.204435689753, 11437.885621144014], "value": 8138.2433524884445, "unit": "score"}], "name": "dromaeo_css", "value": 7610.300371372415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 122.0, 108.0, 108.0, 109.0, 109.0, 111.0, 112.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 148.0, 172.0, 115.0, 148.0, 146.0, 118.0, 178.0, 115.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 160.0, 159.0, 161.0, 161.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 185.0, 76.0, 76.0, 74.0, 75.0, 166.0, 76.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 155.0, 83.0, 83.0, 83.0, 133.0, 84.0, 84.0, 148.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 55.0, 51.0, 50.0, 51.0, 52.0, 52.0, 51.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 69.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 82.0, 76.0, 76.0, 73.0, 78.0, 72.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 119.0, 117.0, 125.0, 129.0, 124.0, 115.0, 126.0, 192.0, 126.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 151.0, 150.0, 149.0, 150.0, 148.0, 151.0, 159.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1496.7, "alertThreshold": 2.0}]} 06:55:12 INFO - Return code: 0 06:55:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:55:12 INFO - # TBPL SUCCESS # 06:55:12 INFO - Running post-action listener: _resource_record_post_action 06:55:12 INFO - [mozharness: 2016-09-08 13:55:12.646526Z] Finished run-tests step (success) 06:55:12 INFO - Running post-run listener: _resource_record_post_run 06:55:12 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 330321920; Read time: 436; Write time: 249376 06:55:12 INFO - TinderboxPrint: CPU usage
13.0% 06:55:12 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 436 06:55:12 INFO - TinderboxPrint: I/O write bytes / time
330,321,920 / 249,376 06:55:12 INFO - TinderboxPrint: CPU idle
8,249.3 (87.0%) 06:55:12 INFO - TinderboxPrint: CPU user
1,187.2 (12.5%) 06:55:12 INFO - TinderboxPrint: Swap in / out
0 / 0 06:55:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 77201408; Read time: 188; Write time: 75500 06:55:12 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250658816; Read time: 236; Write time: 173644 06:55:13 INFO - Running post-run listener: _upload_blobber_files 06:55:13 INFO - Blob upload gear active. 06:55:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:55:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:55:13 INFO - (blobuploader) - INFO - Open directory for files ... 06:55:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:55:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:55:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:55:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:55:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:55:14 INFO - (blobuploader) - INFO - Done attempting. 06:55:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:55:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:55:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:55:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:55:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:55:15 INFO - (blobuploader) - INFO - Done attempting. 06:55:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:55:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:55:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:55:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:55:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:55:19 INFO - (blobuploader) - INFO - Done attempting. 06:55:19 INFO - (blobuploader) - INFO - Iteration through files over. 06:55:19 INFO - Return code: 0 06:55:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:55:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:55:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e21de4d82ff4f00aff4752636fe38143918e36936bc6e654bdcf94c283b547fa50d177344314c478163cae284413c0312d6dff3425a8cfdb6bb9647b50d92489", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b27a10cb3f57fa29e74716a161fc8a11bd34caa3cd40ea7adc8addc892b958b17803b9b9ceee4c230cc396d4a445cc682a019b9420de2c7b553f3aaaf8fb148", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9aba6543a988ec0af7edd7b169e59397347cd61e793c7e17f2dc428aba1c8f7081e9c317ecb6b0d8182ec807c30d315788c89e742f0d6042d5b2ea463d0260c"} 06:55:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:55:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:55:19 INFO - Contents: 06:55:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e21de4d82ff4f00aff4752636fe38143918e36936bc6e654bdcf94c283b547fa50d177344314c478163cae284413c0312d6dff3425a8cfdb6bb9647b50d92489", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b27a10cb3f57fa29e74716a161fc8a11bd34caa3cd40ea7adc8addc892b958b17803b9b9ceee4c230cc396d4a445cc682a019b9420de2c7b553f3aaaf8fb148", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9aba6543a988ec0af7edd7b169e59397347cd61e793c7e17f2dc428aba1c8f7081e9c317ecb6b0d8182ec807c30d315788c89e742f0d6042d5b2ea463d0260c"} 06:55:19 INFO - Running post-run listener: copy_logs_to_upload_dir 06:55:19 INFO - Copying logs to upload dir... 06:55:19 INFO - mkdir: /builds/slave/test/build/upload/logs 06:55:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.915139 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-09-08 06:55:19.154425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 06:55:19.158132) ========= 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=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e21de4d82ff4f00aff4752636fe38143918e36936bc6e654bdcf94c283b547fa50d177344314c478163cae284413c0312d6dff3425a8cfdb6bb9647b50d92489", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b27a10cb3f57fa29e74716a161fc8a11bd34caa3cd40ea7adc8addc892b958b17803b9b9ceee4c230cc396d4a445cc682a019b9420de2c7b553f3aaaf8fb148", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9aba6543a988ec0af7edd7b169e59397347cd61e793c7e17f2dc428aba1c8f7081e9c317ecb6b0d8182ec807c30d315788c89e742f0d6042d5b2ea463d0260c"} build_url:https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/L3U85OqYTHeBRwlZVnmmsw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e21de4d82ff4f00aff4752636fe38143918e36936bc6e654bdcf94c283b547fa50d177344314c478163cae284413c0312d6dff3425a8cfdb6bb9647b50d92489", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b27a10cb3f57fa29e74716a161fc8a11bd34caa3cd40ea7adc8addc892b958b17803b9b9ceee4c230cc396d4a445cc682a019b9420de2c7b553f3aaaf8fb148", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9aba6543a988ec0af7edd7b169e59397347cd61e793c7e17f2dc428aba1c8f7081e9c317ecb6b0d8182ec807c30d315788c89e742f0d6042d5b2ea463d0260c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 06:55:19.185847) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:55:19.186580) ========= 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=0333a256a5e4f052382dd180000001b1-1473341240.922181-1621927936 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 06:55:19.270275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 06:55:19.270653) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 06:55:19.270989) ========= ========= Total master_lag: 0.18 =========