builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1477012522.24 results: success (0) buildid: 20161020152750 builduid: 17bfe7611cba4906a3050259ed535b18 revision: 2bb6dc758711c00d84246d74b57e5aa6cae4b447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.238275) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.238752) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.239080) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.276960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.277546) ========= 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=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-20 18:15: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.3M=0.001s 2016-10-20 18:15:22 (22.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151065 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.465992) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.466371) ========= 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=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018269 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.516912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:22.517284) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2bb6dc758711c00d84246d74b57e5aa6cae4b447 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2bb6dc758711c00d84246d74b57e5aa6cae4b447 --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=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-20 18:15:22,563 truncating revision to first 12 chars 2016-10-20 18:15:22,563 Setting DEBUG logging. 2016-10-20 18:15:22,563 attempt 1/10 2016-10-20 18:15:22,563 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2bb6dc758711?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 18:15:22,882 unpacking tar archive at: mozilla-beta-2bb6dc758711/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:23.195805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:23.196226) ========= script_repo_revision: 2bb6dc758711c00d84246d74b57e5aa6cae4b447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:23.196717) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:23.197034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:15:23.208888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-10-20 18:15:23.209562) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:23 INFO - MultiFileLogger online at 20161020 18:15:23 in /builds/slave/test 18:15:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:15:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:23 INFO - {'append_to_log': False, 18:15:23 INFO - 'base_work_dir': '/builds/slave/test', 18:15:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:15:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:23 INFO - 'branch': 'Mozilla-Beta', 18:15:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:23 INFO - 'config_files': ('talos/linux_config.py',), 18:15:23 INFO - 'default_actions': ('clobber', 18:15:23 INFO - 'read-buildbot-config', 18:15:23 INFO - 'download-and-extract', 18:15:23 INFO - 'populate-webroot', 18:15:23 INFO - 'create-virtualenv', 18:15:23 INFO - 'install', 18:15:23 INFO - 'run-tests'), 18:15:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:23 INFO - 'download_minidump_stackwalk': True, 18:15:23 INFO - 'download_symbols': 'ondemand', 18:15:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:23 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:23 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:23 INFO - 'installer_path': 'installer.exe', 18:15:23 INFO - 'log_level': 'info', 18:15:23 INFO - 'log_name': 'talos', 18:15:23 INFO - 'log_to_console': True, 18:15:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:23 INFO - 'opt_config_files': (), 18:15:23 INFO - 'pip_index': False, 18:15:23 INFO - 'sps_profile': False, 18:15:23 INFO - 'sps_profile_interval': 0, 18:15:23 INFO - 'suite': 'dromaeojs', 18:15:23 INFO - 'system_bits': '32', 18:15:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:23 INFO - 'title': 'talos-linux64-ix-011', 18:15:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:23 INFO - 'use_talos_json': True, 18:15:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:23 INFO - 'work_dir': 'build'} 18:15:23 INFO - [mozharness: 2016-10-21 01:15:23.482746Z] Running clobber step. 18:15:23 INFO - Running pre-action listener: _resource_record_pre_action 18:15:23 INFO - Running main action method: clobber 18:15:23 INFO - rmtree: /builds/slave/test/build 18:15:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:23 INFO - Running post-action listener: _resource_record_post_action 18:15:23 INFO - [mozharness: 2016-10-21 01:15:23.900388Z] Finished clobber step (success) 18:15:23 INFO - [mozharness: 2016-10-21 01:15:23.900489Z] Running read-buildbot-config step. 18:15:23 INFO - Running pre-action listener: _resource_record_pre_action 18:15:23 INFO - Running main action method: read_buildbot_config 18:15:23 INFO - Using buildbot properties: 18:15:23 INFO - { 18:15:23 INFO - "project": "", 18:15:23 INFO - "product": "firefox", 18:15:23 INFO - "script_repo_revision": "production", 18:15:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:15:23 INFO - "repository": "", 18:15:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:15:23 INFO - "buildid": "20161020152750", 18:15:23 INFO - "pgo_build": "True", 18:15:23 INFO - "basedir": "/builds/slave/test", 18:15:23 INFO - "buildnumber": 435, 18:15:23 INFO - "slavename": "talos-linux64-ix-011", 18:15:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:15:23 INFO - "platform": "ubuntu64_hw", 18:15:23 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:15:23 INFO - "branch": "mozilla-beta", 18:15:23 INFO - "repo_path": "releases/mozilla-beta", 18:15:23 INFO - "revision": "2bb6dc758711c00d84246d74b57e5aa6cae4b447", 18:15:23 INFO - "stage_platform": "linux64-pgo", 18:15:23 INFO - "builduid": "17bfe7611cba4906a3050259ed535b18", 18:15:23 INFO - "slavebuilddir": "test" 18:15:23 INFO - } 18:15:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 18:15:23 INFO - Running post-action listener: _resource_record_post_action 18:15:23 INFO - [mozharness: 2016-10-21 01:15:23.902909Z] Finished read-buildbot-config step (success) 18:15:23 INFO - [mozharness: 2016-10-21 01:15:23.902997Z] Running download-and-extract step. 18:15:23 INFO - Running pre-action listener: _resource_record_pre_action 18:15:23 INFO - Running main action method: download_and_extract 18:15:23 INFO - mkdir: /builds/slave/test/build/tests 18:15: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')]} 18:15:23 INFO - https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:15:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:15:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:15:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:15:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:15:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:15:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:15:23 INFO - retry: Failed, sleeping 30 seconds before retrying 18:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 18:15:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:15:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:15:53 INFO - retry: Failed, sleeping 60 seconds before retrying 18:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 18:16:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:16:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:16:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 18:16:54 INFO - Caught exception: HTTP Error 404: Not Found 18:16:54 INFO - Caught exception: HTTP Error 404: Not Found 18:16:54 INFO - Caught exception: HTTP Error 404: Not Found 18:16:54 INFO - trying https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:16:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:16:54 INFO - Downloaded 1327 bytes. 18:16:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:16:54 INFO - Using the following test package requirements: 18:16:54 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:16:54 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:16:54 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 18:16:54 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'jsshell-linux-x86_64.zip'], 18:16:54 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:16:54 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:16:54 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 18:16:54 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 18:16:54 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:16:54 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:16:54 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:16:54 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:16: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')]} 18:16:54 INFO - https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:16:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:16:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:16:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:16:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:16:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:16:54 INFO - retry: Failed, sleeping 30 seconds before retrying 18:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 18:17:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:17:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:17:24 INFO - retry: Failed, sleeping 60 seconds before retrying 18:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 18:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:18:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 18:18:24 INFO - Caught exception: HTTP Error 404: Not Found 18:18:24 INFO - Caught exception: HTTP Error 404: Not Found 18:18:24 INFO - Caught exception: HTTP Error 404: Not Found 18:18:24 INFO - trying https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:25 INFO - Downloaded 21775743 bytes. 18:18:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:18:26 INFO - Return code: 0 18:18:26 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:18: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')]} 18:18:26 INFO - https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:18:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:18:26 INFO - retry: Failed, sleeping 30 seconds before retrying 18:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 18:18:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:18:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:18:56 INFO - retry: Failed, sleeping 60 seconds before retrying 18:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 18:19:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:19:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:19:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 18:19:56 INFO - Caught exception: HTTP Error 404: Not Found 18:19:56 INFO - Caught exception: HTTP Error 404: Not Found 18:19:56 INFO - Caught exception: HTTP Error 404: Not Found 18:19:56 INFO - trying https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:19:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:19:57 INFO - Downloaded 21775743 bytes. 18:19:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:19:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:19:59 INFO - Return code: 0 18:19: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')]} 18:19:59 INFO - https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:19:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:19:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:19:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:19:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:19:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:19:59 INFO - retry: Failed, sleeping 30 seconds before retrying 18:20:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 18:20:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:20:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:20:29 INFO - retry: Failed, sleeping 60 seconds before retrying 18:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 18:21:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:21:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:21:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 18:21:29 INFO - Caught exception: HTTP Error 404: Not Found 18:21:29 INFO - Caught exception: HTTP Error 404: Not Found 18:21:29 INFO - Caught exception: HTTP Error 404: Not Found 18:21:29 INFO - trying https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:21:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:21:30 INFO - Downloaded 14413762 bytes. 18:21:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:21:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:21:30 INFO - Return code: 0 18:21: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')]} 18:21:30 INFO - https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:21:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:21:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:21:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:21:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:21:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:21:30 INFO - retry: Failed, sleeping 30 seconds before retrying 18:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 18:22:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:22:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:22:00 INFO - retry: Failed, sleeping 60 seconds before retrying 18:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 18:23:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:23:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:23:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 18:23:00 INFO - Caught exception: HTTP Error 404: Not Found 18:23:00 INFO - Caught exception: HTTP Error 404: Not Found 18:23:00 INFO - Caught exception: HTTP Error 404: Not Found 18:23:00 INFO - trying https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:23:01 INFO - Downloaded 56787814 bytes. 18:23:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:23:01 INFO - mkdir: /builds/slave/test/properties 18:23:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:01 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:01 INFO - Contents: 18:23:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:23:02 INFO - Running post-action listener: _resource_record_post_action 18:23:02 INFO - Running post-action listener: set_extra_try_arguments 18:23:02 INFO - [mozharness: 2016-10-21 01:23:02.274503Z] Finished download-and-extract step (success) 18:23:02 INFO - [mozharness: 2016-10-21 01:23:02.274736Z] Running populate-webroot step. 18:23:02 INFO - Running pre-action listener: _resource_record_pre_action 18:23:02 INFO - Running main action method: populate_webroot 18:23:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:02 INFO - '--tppagecycles', 18:23:02 INFO - '7'], 18:23:02 INFO - 'tests': ['tsvgm']}}, 18:23:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['damp', 'tps']}, 18:23:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'tests': ['damp', 'tps']}, 18:23:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['dromaeo_dom']}, 18:23:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['basic_compositor_video']}, 18:23:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['a11yr', 18:23:02 INFO - 'ts_paint', 18:23:02 INFO - 'tpaint', 18:23:02 INFO - 'sessionrestore', 18:23:02 INFO - 'sessionrestore_no_auto_restore', 18:23:02 INFO - 'tabpaint']}, 18:23:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['tsvgx', 18:23:02 INFO - 'tsvgr_opacity', 18:23:02 INFO - 'tart', 18:23:02 INFO - 'tscrollx', 18:23:02 INFO - 'cart']}, 18:23:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:02 INFO - 'tsvgr_opacity', 18:23:02 INFO - 'tart', 18:23:02 INFO - 'tscrollx', 18:23:02 INFO - 'cart']}, 18:23:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'talos_options': ['--disable-e10s'], 18:23:02 INFO - 'tests': ['tp5o']}, 18:23:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'tests': ['tp5o']}, 18:23:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'talos_options': ['--disable-e10s', 18:23:02 INFO - '--xperf_path', 18:23:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:02 INFO - 'tests': ['tp5n']}, 18:23:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:23:02 INFO - 'talos_options': ['--xperf_path', 18:23:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:02 INFO - 'tests': ['tp5n']}}, 18:23:02 INFO - 'talos.zip': {'path': '', 18:23:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:02 INFO - Running post-action listener: _resource_record_post_action 18:23:02 INFO - [mozharness: 2016-10-21 01:23:02.296357Z] Finished populate-webroot step (success) 18:23:02 INFO - [mozharness: 2016-10-21 01:23:02.296447Z] Running create-virtualenv step. 18:23:02 INFO - Running pre-action listener: _resource_record_pre_action 18:23:02 INFO - Running main action method: create_virtualenv 18:23:02 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23: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 18:23:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:02 INFO - Using real prefix '/usr' 18:23:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:03 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:05 INFO - Installing pip.................done. 18:23:05 INFO - Return code: 0 18:23:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:05 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:05 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 18:23:05 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 18:23:05 INFO - Using env: {'DISPLAY': ':0', 18:23:05 INFO - 'HOME': '/home/cltbld', 18:23:05 INFO - 'LANG': 'en_US.UTF-8', 18:23:05 INFO - 'LANGUAGE': 'en_US:en', 18:23:05 INFO - 'LOGNAME': 'cltbld', 18:23:05 INFO - 'MAIL': '/var/mail/cltbld', 18:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:05 INFO - 'MOZ_NO_REMOTE': '1', 18:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:05 INFO - 'NO_EM_RESTART': '1', 18:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:05 INFO - 'PWD': '/builds/slave/test', 18:23:05 INFO - 'SHELL': '/bin/bash', 18:23:05 INFO - 'SHLVL': '1', 18:23:05 INFO - 'TERM': 'linux', 18:23:05 INFO - 'TMOUT': '86400', 18:23:05 INFO - 'USER': 'cltbld', 18:23:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:05 INFO - '_': '/tools/buildbot/bin/python'} 18:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:05 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 18:23:05 INFO - Cleaning up... 18:23:05 INFO - Return code: 0 18:23:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:05 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:05 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 18:23:05 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 18:23:05 INFO - Using env: {'DISPLAY': ':0', 18:23:05 INFO - 'HOME': '/home/cltbld', 18:23:05 INFO - 'LANG': 'en_US.UTF-8', 18:23:05 INFO - 'LANGUAGE': 'en_US:en', 18:23:05 INFO - 'LOGNAME': 'cltbld', 18:23:05 INFO - 'MAIL': '/var/mail/cltbld', 18:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:05 INFO - 'MOZ_NO_REMOTE': '1', 18:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:05 INFO - 'NO_EM_RESTART': '1', 18:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:05 INFO - 'PWD': '/builds/slave/test', 18:23:05 INFO - 'SHELL': '/bin/bash', 18:23:05 INFO - 'SHLVL': '1', 18:23:05 INFO - 'TERM': 'linux', 18:23:05 INFO - 'TMOUT': '86400', 18:23:05 INFO - 'USER': 'cltbld', 18:23:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:05 INFO - '_': '/tools/buildbot/bin/python'} 18:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:05 INFO - Downloading/unpacking psutil>=3.1.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:07 INFO - Installing collected packages: psutil 18:23:07 INFO - Running setup.py install for psutil 18:23:07 INFO - building 'psutil._psutil_linux' extension 18:23: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 18:23:08 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 18:23:09 INFO - building 'psutil._psutil_posix' extension 18:23:09 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 18:23:09 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 18:23:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:09 INFO - Successfully installed psutil 18:23:09 INFO - Cleaning up... 18:23:09 INFO - Return code: 0 18:23:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:23: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 mozsystemmonitor==0.3 18:23:09 INFO - Using env: {'DISPLAY': ':0', 18:23:09 INFO - 'HOME': '/home/cltbld', 18:23:09 INFO - 'LANG': 'en_US.UTF-8', 18:23:09 INFO - 'LANGUAGE': 'en_US:en', 18:23:09 INFO - 'LOGNAME': 'cltbld', 18:23:09 INFO - 'MAIL': '/var/mail/cltbld', 18:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:09 INFO - 'MOZ_NO_REMOTE': '1', 18:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:09 INFO - 'NO_EM_RESTART': '1', 18:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:09 INFO - 'PWD': '/builds/slave/test', 18:23:09 INFO - 'SHELL': '/bin/bash', 18:23:09 INFO - 'SHLVL': '1', 18:23:09 INFO - 'TERM': 'linux', 18:23:09 INFO - 'TMOUT': '86400', 18:23:09 INFO - 'USER': 'cltbld', 18:23:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:09 INFO - '_': '/tools/buildbot/bin/python'} 18:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:23:11 INFO - Installing collected packages: mozsystemmonitor 18:23:11 INFO - Running setup.py install for mozsystemmonitor 18:23:11 INFO - Successfully installed mozsystemmonitor 18:23:11 INFO - Cleaning up... 18:23:11 INFO - Return code: 0 18:23:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:23:11 INFO - Using env: {'DISPLAY': ':0', 18:23:11 INFO - 'HOME': '/home/cltbld', 18:23:11 INFO - 'LANG': 'en_US.UTF-8', 18:23:11 INFO - 'LANGUAGE': 'en_US:en', 18:23:11 INFO - 'LOGNAME': 'cltbld', 18:23:11 INFO - 'MAIL': '/var/mail/cltbld', 18:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:11 INFO - 'MOZ_NO_REMOTE': '1', 18:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:11 INFO - 'NO_EM_RESTART': '1', 18:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:11 INFO - 'PWD': '/builds/slave/test', 18:23:11 INFO - 'SHELL': '/bin/bash', 18:23:11 INFO - 'SHLVL': '1', 18:23:11 INFO - 'TERM': 'linux', 18:23:11 INFO - 'TMOUT': '86400', 18:23:11 INFO - 'USER': 'cltbld', 18:23:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:11 INFO - '_': '/tools/buildbot/bin/python'} 18:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:11 INFO - Downloading/unpacking jsonschema==2.5.1 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23:13 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 18:23:13 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 18:23:13 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 18:23:13 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 18:23:13 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 18:23:13 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 18:23:14 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:14 INFO - warning: no files found matching '*.txt' 18:23:14 INFO - no previously-included directories found matching 'build' 18:23:14 INFO - no previously-included directories found matching 'dist' 18:23:14 INFO - no previously-included directories found matching '.git*' 18:23:14 INFO - Installing collected packages: jsonschema, functools32 18:23:14 INFO - Running setup.py install for functools32 18:23:14 INFO - warning: no files found matching '*.txt' 18:23:14 INFO - no previously-included directories found matching 'build' 18:23:14 INFO - no previously-included directories found matching 'dist' 18:23:14 INFO - no previously-included directories found matching '.git*' 18:23:14 INFO - Successfully installed jsonschema functools32 18:23:14 INFO - Cleaning up... 18:23:14 INFO - Return code: 0 18:23:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:14 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:14 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 18:23:14 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 18:23:14 INFO - Using env: {'DISPLAY': ':0', 18:23:14 INFO - 'HOME': '/home/cltbld', 18:23:14 INFO - 'LANG': 'en_US.UTF-8', 18:23:14 INFO - 'LANGUAGE': 'en_US:en', 18:23:14 INFO - 'LOGNAME': 'cltbld', 18:23:14 INFO - 'MAIL': '/var/mail/cltbld', 18:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:14 INFO - 'MOZ_NO_REMOTE': '1', 18:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:14 INFO - 'NO_EM_RESTART': '1', 18:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:14 INFO - 'PWD': '/builds/slave/test', 18:23:14 INFO - 'SHELL': '/bin/bash', 18:23:14 INFO - 'SHLVL': '1', 18:23:14 INFO - 'TERM': 'linux', 18:23:14 INFO - 'TMOUT': '86400', 18:23:14 INFO - 'USER': 'cltbld', 18:23:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:14 INFO - '_': '/tools/buildbot/bin/python'} 18:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:23:14 INFO - Cleaning up... 18:23:14 INFO - Return code: 0 18:23:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:14 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:14 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 18:23:14 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 18:23:14 INFO - Using env: {'DISPLAY': ':0', 18:23:14 INFO - 'HOME': '/home/cltbld', 18:23:14 INFO - 'LANG': 'en_US.UTF-8', 18:23:14 INFO - 'LANGUAGE': 'en_US:en', 18:23:14 INFO - 'LOGNAME': 'cltbld', 18:23:14 INFO - 'MAIL': '/var/mail/cltbld', 18:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:14 INFO - 'MOZ_NO_REMOTE': '1', 18:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:14 INFO - 'NO_EM_RESTART': '1', 18:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:14 INFO - 'PWD': '/builds/slave/test', 18:23:14 INFO - 'SHELL': '/bin/bash', 18:23:14 INFO - 'SHLVL': '1', 18:23:14 INFO - 'TERM': 'linux', 18:23:14 INFO - 'TMOUT': '86400', 18:23:14 INFO - 'USER': 'cltbld', 18:23:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:14 INFO - '_': '/tools/buildbot/bin/python'} 18:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:14 INFO - Downloading/unpacking blobuploader==1.2.4 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:16 INFO - Downloading blobuploader-1.2.4.tar.gz 18:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:23:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:23:16 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 18:23:16 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 18:23:16 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 18:23:16 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 18:23:16 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 18:23:16 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 18:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:23:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:23:17 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 18:23:17 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 18:23:17 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 18:23:17 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 18:23:17 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 18:23:17 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 18:23:17 INFO - Downloading docopt-0.6.1.tar.gz 18:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:23:17 INFO - Installing collected packages: blobuploader, requests, docopt 18:23:17 INFO - Running setup.py install for blobuploader 18:23:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:23:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:23:17 INFO - Running setup.py install for requests 18:23:17 INFO - Running setup.py install for docopt 18:23:18 INFO - Successfully installed blobuploader requests docopt 18:23:18 INFO - Cleaning up... 18:23:18 INFO - Return code: 0 18:23:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:18 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')]} 18:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:18 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')]} 18:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:18 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:18 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 18:23:18 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 18:23:18 INFO - Using env: {'DISPLAY': ':0', 18:23:18 INFO - 'HOME': '/home/cltbld', 18:23:18 INFO - 'LANG': 'en_US.UTF-8', 18:23:18 INFO - 'LANGUAGE': 'en_US:en', 18:23:18 INFO - 'LOGNAME': 'cltbld', 18:23:18 INFO - 'MAIL': '/var/mail/cltbld', 18:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:18 INFO - 'MOZ_NO_REMOTE': '1', 18:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:18 INFO - 'NO_EM_RESTART': '1', 18:23:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:18 INFO - 'PWD': '/builds/slave/test', 18:23:18 INFO - 'SHELL': '/bin/bash', 18:23:18 INFO - 'SHLVL': '1', 18:23:18 INFO - 'TERM': 'linux', 18:23:18 INFO - 'TMOUT': '86400', 18:23:18 INFO - 'USER': 'cltbld', 18:23:18 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:18 INFO - '_': '/tools/buildbot/bin/python'} 18:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:18 INFO - Running setup.py (path:/tmp/pip-9QpZw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:18 INFO - Running setup.py (path:/tmp/pip-7xJp6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:18 INFO - Running setup.py (path:/tmp/pip-73xpqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:18 INFO - Running setup.py (path:/tmp/pip-gkpf4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:18 INFO - Running setup.py (path:/tmp/pip-0cWCZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:18 INFO - Running setup.py (path:/tmp/pip-Ppvw0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:18 INFO - Running setup.py (path:/tmp/pip-lUfgEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:18 INFO - Running setup.py (path:/tmp/pip-OMv9BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:18 INFO - Running setup.py (path:/tmp/pip-hRxAIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:18 INFO - Running setup.py (path:/tmp/pip-70EzBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:19 INFO - Running setup.py (path:/tmp/pip-uc6zia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:19 INFO - Running setup.py (path:/tmp/pip-efiDil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:19 INFO - Running setup.py (path:/tmp/pip-WywNys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:19 INFO - Running setup.py (path:/tmp/pip-fFypHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:19 INFO - Running setup.py (path:/tmp/pip-9pVjz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:19 INFO - Running setup.py (path:/tmp/pip-NV2g2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:19 INFO - Running setup.py (path:/tmp/pip-azTVLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:23:19 INFO - Running setup.py install for manifestparser 18:23:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:23:19 INFO - Running setup.py install for mozcrash 18:23:19 INFO - Running setup.py install for mozdebug 18:23:19 INFO - Running setup.py install for mozdevice 18:23:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:23:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:23:19 INFO - Running setup.py install for mozfile 18:23:20 INFO - Running setup.py install for mozhttpd 18:23:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozinfo 18:23:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozInstall 18:23:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozleak 18:23:20 INFO - Running setup.py install for mozlog 18:23:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for moznetwork 18:23:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozprocess 18:23:20 INFO - Running setup.py install for mozprofile 18:23:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozrunner 18:23:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:20 INFO - Running setup.py install for mozscreenshot 18:23:21 INFO - Running setup.py install for moztest 18:23:21 INFO - Running setup.py install for mozversion 18:23:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:21 INFO - Cleaning up... 18:23:21 INFO - Return code: 0 18:23:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:21 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')]} 18:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:21 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')]} 18:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:21 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:21 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 18:23:21 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 18:23:21 INFO - Using env: {'DISPLAY': ':0', 18:23:21 INFO - 'HOME': '/home/cltbld', 18:23:21 INFO - 'LANG': 'en_US.UTF-8', 18:23:21 INFO - 'LANGUAGE': 'en_US:en', 18:23:21 INFO - 'LOGNAME': 'cltbld', 18:23:21 INFO - 'MAIL': '/var/mail/cltbld', 18:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:21 INFO - 'MOZ_NO_REMOTE': '1', 18:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:21 INFO - 'NO_EM_RESTART': '1', 18:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:21 INFO - 'PWD': '/builds/slave/test', 18:23:21 INFO - 'SHELL': '/bin/bash', 18:23:21 INFO - 'SHLVL': '1', 18:23:21 INFO - 'TERM': 'linux', 18:23:21 INFO - 'TMOUT': '86400', 18:23:21 INFO - 'USER': 'cltbld', 18:23:21 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:21 INFO - '_': '/tools/buildbot/bin/python'} 18:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:21 INFO - Running setup.py (path:/tmp/pip-5bwPlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:21 INFO - Running setup.py (path:/tmp/pip-gqOcZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:21 INFO - Running setup.py (path:/tmp/pip-rrJ2cT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:21 INFO - Running setup.py (path:/tmp/pip-iooMkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:21 INFO - Running setup.py (path:/tmp/pip-qO8DTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:21 INFO - Running setup.py (path:/tmp/pip-noYaEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:21 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)) 18:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:21 INFO - Running setup.py (path:/tmp/pip-ZPcbV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:22 INFO - Running setup.py (path:/tmp/pip-G14LSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:22 INFO - Running setup.py (path:/tmp/pip-2K1g9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:22 INFO - Running setup.py (path:/tmp/pip-9nIcrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:22 INFO - Running setup.py (path:/tmp/pip-Pd5UAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:22 INFO - Running setup.py (path:/tmp/pip-6Xt1SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:22 INFO - Running setup.py (path:/tmp/pip-tsWeS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:22 INFO - Running setup.py (path:/tmp/pip-AhYA0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:22 INFO - Running setup.py (path:/tmp/pip-I4N7lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:22 INFO - Running setup.py (path:/tmp/pip-PWT6iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:22 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)) 18:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:22 INFO - Running setup.py (path:/tmp/pip-CZdgfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:22 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)) 18:23:22 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)) 18:23:22 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)) 18:23:22 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)) 18:23:22 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)) 18:23:22 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)) 18:23:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:22 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 18:23:22 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 18:23:22 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 18:23:22 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 18:23:22 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 18:23:22 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 18:23:24 INFO - Downloading blessings-1.6.tar.gz 18:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:23:24 INFO - Installing collected packages: blessings 18:23:24 INFO - Running setup.py install for blessings 18:23:24 INFO - Successfully installed blessings 18:23:24 INFO - Cleaning up... 18:23:24 INFO - Return code: 0 18:23:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:25 INFO - Reading from file tmpfile_stdout 18:23:25 INFO - Current package versions: 18:23:25 INFO - argparse == 1.2.1 18:23:25 INFO - blessings == 1.6 18:23:25 INFO - blobuploader == 1.2.4 18:23:25 INFO - docopt == 0.6.1 18:23:25 INFO - functools32 == 3.2.3-2 18:23:25 INFO - jsonschema == 2.5.1 18:23:25 INFO - manifestparser == 1.1 18:23:25 INFO - mozInstall == 1.12 18:23:25 INFO - mozcrash == 0.17 18:23:25 INFO - mozdebug == 0.1 18:23:25 INFO - mozdevice == 0.48 18:23:25 INFO - mozfile == 1.2 18:23:25 INFO - mozhttpd == 0.7 18:23:25 INFO - mozinfo == 0.9 18:23:25 INFO - mozleak == 0.1 18:23:25 INFO - mozlog == 3.3 18:23:25 INFO - moznetwork == 0.27 18:23:25 INFO - mozprocess == 0.23 18:23:25 INFO - mozprofile == 0.28 18:23:25 INFO - mozrunner == 6.12 18:23:25 INFO - mozscreenshot == 0.1 18:23:25 INFO - mozsystemmonitor == 0.3 18:23:25 INFO - moztest == 0.7 18:23:25 INFO - mozversion == 1.4 18:23:25 INFO - psutil == 3.1.1 18:23:25 INFO - requests == 1.2.3 18:23:25 INFO - wsgiref == 0.1.2 18:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:25 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:25 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 18:23:25 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 18:23:25 INFO - Using env: {'DISPLAY': ':0', 18:23:25 INFO - 'HOME': '/home/cltbld', 18:23:25 INFO - 'LANG': 'en_US.UTF-8', 18:23:25 INFO - 'LANGUAGE': 'en_US:en', 18:23:25 INFO - 'LOGNAME': 'cltbld', 18:23:25 INFO - 'MAIL': '/var/mail/cltbld', 18:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:25 INFO - 'MOZ_NO_REMOTE': '1', 18:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:25 INFO - 'NO_EM_RESTART': '1', 18:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:25 INFO - 'PWD': '/builds/slave/test', 18:23:25 INFO - 'SHELL': '/bin/bash', 18:23:25 INFO - 'SHLVL': '1', 18:23:25 INFO - 'TERM': 'linux', 18:23:25 INFO - 'TMOUT': '86400', 18:23:25 INFO - 'USER': 'cltbld', 18:23:25 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:25 INFO - '_': '/tools/buildbot/bin/python'} 18:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 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)) 18:23:25 INFO - Cleaning up... 18:23:25 INFO - Return code: 0 18:23:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:25 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 0x164c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x190d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', '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 18:23:25 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 18:23:25 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 18:23:25 INFO - Using env: {'DISPLAY': ':0', 18:23:25 INFO - 'HOME': '/home/cltbld', 18:23:25 INFO - 'LANG': 'en_US.UTF-8', 18:23:25 INFO - 'LANGUAGE': 'en_US:en', 18:23:25 INFO - 'LOGNAME': 'cltbld', 18:23:25 INFO - 'MAIL': '/var/mail/cltbld', 18:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:25 INFO - 'MOZ_NO_REMOTE': '1', 18:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:25 INFO - 'NO_EM_RESTART': '1', 18:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:25 INFO - 'PWD': '/builds/slave/test', 18:23:25 INFO - 'SHELL': '/bin/bash', 18:23:25 INFO - 'SHLVL': '1', 18:23:25 INFO - 'TERM': 'linux', 18:23:25 INFO - 'TMOUT': '86400', 18:23:25 INFO - 'USER': 'cltbld', 18:23:25 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:25 INFO - '_': '/tools/buildbot/bin/python'} 18:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:23:25 INFO - Cleaning up... 18:23:25 INFO - Return code: 0 18:23:25 INFO - Running post-action listener: _resource_record_post_action 18:23:25 INFO - Running post-action listener: _start_resource_monitoring 18:23:25 INFO - Starting resource monitoring. 18:23:25 INFO - [mozharness: 2016-10-21 01:23:25.634122Z] Finished create-virtualenv step (success) 18:23:25 INFO - [mozharness: 2016-10-21 01:23:25.634993Z] Running install step. 18:23:25 INFO - Running pre-action listener: _resource_record_pre_action 18:23:25 INFO - Running main action method: install 18:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:25 INFO - Reading from file tmpfile_stdout 18:23:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:25 INFO - Reading from file tmpfile_stdout 18:23:25 INFO - Output received: 18:23:25 INFO - Usage: mozinstall [options] installer 18:23:25 INFO - Options: 18:23:25 INFO - -h, --help show this help message and exit 18:23:25 INFO - -d DEST, --destination=DEST 18:23:25 INFO - Directory to install application into. [default: 18:23:25 INFO - "/builds/slave/test"] 18:23:25 INFO - --app=APP Application being installed. [default: firefox] 18:23:25 INFO - mkdir: /builds/slave/test/build/application 18:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:23:40 INFO - Reading from file tmpfile_stdout 18:23:40 INFO - Output received: 18:23:40 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:40 INFO - Running post-action listener: _resource_record_post_action 18:23:40 INFO - [mozharness: 2016-10-21 01:23:40.586699Z] Finished install step (success) 18:23:40 INFO - [mozharness: 2016-10-21 01:23:40.586975Z] Running run-tests step. 18:23:40 INFO - Running pre-action listener: _resource_record_pre_action 18:23:40 INFO - Running main action method: run_tests 18:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:40 INFO - Python 2.7.3 18:23:40 INFO - Return code: 0 18:23:40 INFO - grabbing minidump binary from tooltool 18:23: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')]} 18:23: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 0x190b3b0>, '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 0x190c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x190c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23: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 18:23: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 18:23: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 18:23:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:40 INFO - Return code: 0 18:23:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 18:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 18:23:40 INFO - Using env: {'DISPLAY': ':0', 18:23:40 INFO - 'HOME': '/home/cltbld', 18:23:40 INFO - 'LANG': 'en_US.UTF-8', 18:23:40 INFO - 'LANGUAGE': 'en_US:en', 18:23:40 INFO - 'LOGNAME': 'cltbld', 18:23:40 INFO - 'MAIL': '/var/mail/cltbld', 18:23:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:40 INFO - 'MOZ_NO_REMOTE': '1', 18:23:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:40 INFO - 'NO_EM_RESTART': '1', 18:23:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:40 INFO - 'PWD': '/builds/slave/test', 18:23:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:40 INFO - 'SHELL': '/bin/bash', 18:23:40 INFO - 'SHLVL': '1', 18:23:40 INFO - 'TERM': 'linux', 18:23:40 INFO - 'TMOUT': '86400', 18:23:40 INFO - 'USER': 'cltbld', 18:23:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594', 18:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:40 INFO - '_': '/tools/buildbot/bin/python'} 18:23:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 18:23:40 INFO - mozversion application_buildid: 20161020152750 18:23:40 INFO - mozversion application_changeset: 2bb6dc758711c00d84246d74b57e5aa6cae4b447 18:23:40 INFO - mozversion application_display_name: Firefox 18:23:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:40 INFO - mozversion application_name: Firefox 18:23:40 INFO - mozversion application_remotingname: firefox 18:23:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:23:40 INFO - mozversion application_vendor: Mozilla 18:23:40 INFO - mozversion application_version: 50.0 18:23:40 INFO - mozversion platform_buildid: 20161020152750 18:23:40 INFO - mozversion platform_changeset: 2bb6dc758711c00d84246d74b57e5aa6cae4b447 18:23:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:23:40 INFO - mozversion platform_version: 50.0 18:23:40 INFO - using testdate: 1477013020 18:23:40 INFO - actual date: 1477013020 18:23:40 INFO - starting webserver on 'localhost:57777' 18:23:40 INFO - SUITE-START | Running 2 tests 18:23:40 INFO - TEST-START | dromaeo_css 18:23:40 INFO - operating with platform_type : linux_ 18:23:40 INFO - Initialising browser for dromaeo_css test... 18:23:40 INFO - TEST-INFO | started process 3333 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCYQCWf/profile http://localhost:57777/getInfo.html) 18:23:45 INFO - PROCESS | 3333 | __metrics Screen width/height:1600/1200 18:23:45 INFO - PROCESS | 3333 | colorDepth:24 18:23:45 INFO - PROCESS | 3333 | Browser inner width/height: 1024/697 18:23:45 INFO - PROCESS | 3333 | __metrics 18:23:46 INFO - PROCESS | 3333 | 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" 18:23:46 INFO - PROCESS | 3333 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:23:47 INFO - TEST-INFO | 3333: exit 0 18:23:47 INFO - Browser initialized. 18:23:47 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:47 INFO - TEST-INFO | started process 3417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCYQCWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:59 INFO - PROCESS | 3417 | RSS: Main: 144433152 18:23:59 INFO - PROCESS | 3417 | 18:24:50 INFO - PROCESS | 3417 | 1477013090849 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] 18:26:59 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57777/tests/dromaeo/cssquery-ext.html) 18:27:00 INFO - PROCESS | 3417 | RSS: Main: 261812224 18:27:00 INFO - PROCESS | 3417 | 18:30:02 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-ext.html (next: http://localhost:57777/tests/dromaeo/cssquery-jquery.html) 18:30:03 INFO - PROCESS | 3417 | RSS: Main: 230129664 18:30:03 INFO - PROCESS | 3417 | 18:33:05 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57777/tests/dromaeo/cssquery-mootools.html) 18:33:06 INFO - PROCESS | 3417 | RSS: Main: 176898048 18:33:06 INFO - PROCESS | 3417 | 18:36:07 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57777/tests/dromaeo/cssquery-prototype.html) 18:36:08 INFO - PROCESS | 3417 | RSS: Main: 176889856 18:36:08 INFO - PROCESS | 3417 | 18:39:11 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57777/tests/dromaeo/cssquery-yui.html) 18:39:11 INFO - PROCESS | 3417 | RSS: Main: 178008064 18:39:11 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | Cycle 1(1): loaded http://localhost:57777/tests/dromaeo/cssquery-yui.html (next: http://localhost:57777/tests/dromaeo/cssquery-dojo.html) 18:42:14 INFO - PROCESS | 3417 | RSS: Main: 258199552 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | __start_tp_report 18:42:14 INFO - PROCESS | 3417 | _x_x_mozilla_page_load 18:42:14 INFO - PROCESS | 3417 | _x_x_mozilla_page_load_details 18:42:14 INFO - PROCESS | 3417 | |i|pagename|runs| 18:42:14 INFO - PROCESS | 3417 | |0;dojo.html;3260.4457242268813;3262.2333751568385;3279.55725976993;3292.670732926709;3315.7015868571825;5545.528630066444;5602.7758889644465;5640.069388550889;5644.379118296994;5670.319561652607;5653.982283189024;5704.828855134342;5731.484166425021;5737.627054241471;5737.827865164041;5174.818881339153;5177.870553236171;5183.637145399824;5236.371635403738;5248.5538729208065;4984.950150498503;5022.472640372763;5238.083335416306;5250.422453530127;5276.604254680895;5102.260172275018;5129.204973229155;5130.820421285256;5171.663841850286;5203.115470370028;5195.87010324743;5227.764750586232;5232.424433312332;5234.136367499358;5271.182966640177;4989.850304490871;5110.770015349281;5193.922091168635;5211.947880521184;5220.92168617471;5557.194206840026;5576.944230557683;5620.185073164368;5649.180868774005;5696.487316141567;6058.273007239115;6088.878222435571;6106.48094911933;6110.144579758838;6111.816645500642;5786.334571524287;6147.262328520562;6151.108089327067;6153.969230153865;6160.8767824643255;5587.972060139714;5630.268065151545;5652.85867853307;5670.829875103754;5705.058665320175;6249.6562689051625;6249.906251406142;6260.780872669435;6261.906071408933;6271.498280137578;5723.027085395493;5918.733656985446;5976.731047102804;5977.880442391215;5981;6022.337542870281;6070.119832624245;6077.756889724362;6122.855605810045;6124.228368151265;6066.090086487062;6095.6037857540005;6097.3292937776805;6106.542009349317;6140.4473597376455;5790.623609465456;6122.785702500391;6124.050772130327;6127.050307202391;6136.815895523141;4546.909061818745;4632.652551058684;4649.18639238132;4655.4180727409075;4717.150912835654;4171.436856024458;4177.874663760092;4180.393842892763;4182.53992060873;4209.663226941899;5737.3975732548315;5787.247657804459;5793.333766616893;5795.392073566912;5834.883302333929;5831.125331200354;5957.970210148921;5978.880422391528;6023.548233882389;6120.296165940974;5754.712264386764;5757.453041961007;5759.6832174229985;5765;5769.740361683735;5240.135377662643;5284.7886084556185;5286.524212820865;5298.3906850712665;5301.231321458367;6041.2750469943885;6183.938160618336;6219.813405597839;6229.1590635264565;6351.872962540631;6110.205673262448;6115.388461153849;6119.510439164767;6123.540734445023;6127.969360153171;6335.651539165206;6344.175257216532;6354.936450635434;6358.586691865014;6361.300256971822;5737.196792449144;5865.765369385177;5898.203742494708;5919.200907877382;6007.429294217042;6006.549508786771;6052.485538729257;6070;6070.3019152798;6073.817785466443;5952.5833191676165;5953.553483488842;5964.165016897725;5965.28416590012;5967.552433567413;5735;5857.326407463196;5873.794417195549;5917.142014407973;6026.548008899263;5947.375525569752;5960.046392577166;5961.731722072431;5963.463288304073;5968.880622387441;5693.430656934439;5945.821625351246;5979.40205979416;5996.580239383201;6004.309504406874;5732.369439361751;5834.241548598655;5842.357340692605;5886;5886.205362276038 18:42:14 INFO - PROCESS | 3417 | |1;ext.html;586.4282324733385;644.8645784385279;647.757091090841;656.2158220925992;659.7558903205814;34749.65250347496;47009.294860577116;47918.520814791926;47972.76013619936;48055.75972120143;48978.75510622451;48997.75501122498;49240.261396079;49476;49668.50331496684;8545.145485451467;9304.906950930488;9434.150926416656;9461.053894610553;11157.609483668073;10954.616588419407;11436.37099959502;11459.427028648575;11461.255018423775;11463.541458341657;11564.363959982198;11609;11612.883871161264;11613.070954323633;11630.709232269219;11518.827217591743;11559.479823407966;11564.942175289154;11572.59495917639;11581.363025033663;11287.943560282229;11303.265287756269;11365;11388.202825802196;11393.202475826696;46036;46774.29838552424;46779.53220467786;46781.766091169666;46800.29799553009;3590.317839610492;4220.577942205786;4238.809253583596;4242.703010789246;4550.863474095782;35468.29063418717;35778.28443431143;36313.27373452543;36739.26521469556;37525.81237093824;4541.750203738793;4565.977170114162;4599.471060828014;4655.604273636745;4854.223324268136;376036;377518;377617;378387;378460;39227.41158882619;66471.33528664651;66763;66825;66906;39670.40494392587;41693;41764.58235417668;41827.372589410574;41847.1630567387;17687.823121768874;17762.733558996624;17890.642187156445;17934.820651793576;17935.192916318796;13589.592312230649;13950.860491395159;13951.720965580631;14212.289385530683;14437.494687685881;9348.95325523368;9469.573869175903;9594.184494317922;9596.088371604686;9601.519924003911;3589.838457269429;3592.568891733009;3603.9819800900827;3663.303972245265;3665.2303016366864;1079.4764539198482;1144.3420033480616;1157.5196293538088;1159.8144296912453;1176.2472017908544;35143.4728479073;35463.4680479793;35760;35893.64106358903;35919.10202244912;1171.47869198206;1202.4528839378063;1245.9626211213679;1276.342683517989;1285.2738202915323;16311.429099981444;18621.7206741899;18780;19444.23623707665;19480.610387792163;628076;632229;633829;634561;635628;66207.66896165488;66607.33392666109;66622.66688666526;66722;66761;1321.966950826237;1425.6720954180596;1431.9284035798169;1444.075791493445;1470.5808844479272;32951.34097318088;36049.19754012236;36619.26761464809;37356;37463.43804842984;57895.421045789;60543;62735.37264627478;62783;69454.65272673604;551972;562785;563175;564451;564772;37253;39996.400053999794;40320;50252.49747502478;50406;53376.73311633417;53617.46382536281;53636.46363536471;54028.72985635047;54841.177382340094;45621.08757824892;46246.53753462422;46679.29981050355;47135.52864471448;47930.520694792605;46507;47153.764231178626;47258.291125633834;47545.762271188425;48116.759416202694;45504;46320.536794631626;46511.06977860356;46542.53457465382;48577.514224857296;38507.80746096252;40660.39009414802;41274.8296469232;41590.58409415867;42208.57791422169;31325.59034843483;31335.529967050057;31371.372572547964;31507.21231969219;31684.841575791972 18:42:14 INFO - PROCESS | 3417 | |2;jquery.html;429.99355009674844;432.8398492557752;435.43828461284943;441.37765750292095;446.4776211832156;19612.803871961292;20986.685199721996;21569.24507642231;21673.566528669417;21854.562908741857;20314;21084.789152108475;21210.681839772416;21285.467863303424;21410.35768926931;5770.567207459447;5974.641521508724;6040.57715959883;6041.033434650457;6045.576809623328;5733.655980641175;5846.18153458514;5870.442307980735;5885.117232415129;5888.793892213774;5852.76588936442;5871.853203669921;5877.471027607515;5880.911786323187;5886.705664716768;5762.106873434583;5820.767169313222;5873.1190321451695;5878.823635290948;5888.499477544436;5294.735263236823;5307.840764777063;5319.148936170194;5325.733713314357;5327.866803329909;24794.752052479424;25339.239822805346;25346.112886049083;25382.619260711104;25498;2504.4615407687434;2543.0209369392733;2575.3046677397183;2619.410632607667;2809.2695899066184;14786.18675972821;15710.842891571052;15738.449154279546;15833.762493562606;16221.188940553044;1961.3233434465087;1970.6354324450022;2061.6186005589016;2063.2572273981355;2093.27781915239;59217.91044776119;64625;70617.7629342236;70836.64581677059;76492.61753691251;11278.661640150809;11397.487113079931;11462.541498339973;11593.130515211393;11999.820002699966;15969.201539922957;15971.281292341873;15988.280527376064;15990;15990.840091599168;8690.174433428814;8826.646934122564;8986.19124278818;9018.954905225432;9058.139476749699;8949;8953.776155596162;9003.594838232295;9018.954905225562;9235.676751313671;4773.793274599826;4788.138135135709;4811.013742182844;5219.295395121686;5275.57795376369;2112.4402033461147;2133.5519540896585;2164.415607785889;2177.1835561664375;2201.3395981205576;810.8566920641866;910.8770316007386;930.9022552632011;1009.8333774927202;1095.554500870115;20727.896360518404;21278.574428511343;21359.25242616501;21719.239826605994;21778;991.7718924647371;1004.352192835617;1010.8281592129356;1020.0309705779539;1037.3464717228098;9775.804483910282;11665;11792.41037948099;11853.76292474163;11944;558386;558582;565059;571882;573736;91326.54336728273;91561.54219228994;94592;94680;100796.49601751944;1046.7540128069897;1074.0655629602452;1096.2655021135859;1096.4079397125463;1098.3958822647512;18666.533336666147;19668.311609094184;19697.310594129136;19810.801891980896;19843.206271748968;44521.77739111284;45155.54844451514;45325;45895.3115703258;47961.280580792016;63257;63531;64680;65148.674256628416;65711.34288657244;7906.486078404885;8111.269985701315;8227.86075620331;8388.496690198608;8952.597133128895;11048.337099774039;12144;14078.718425631634;14495.057821241586;14630.268486575416;12432.191907525981;12730.745385092432;12951.532232454838;12970.156939798882;13162.276074815976;12015.81976270374;12694.551321831947;12853.807192891927;13100.541481048118;13151.802722958972;6706.429953453816;12768.233905965672;13050.804237936249;13085.691430856838;13260.138091024439;9383.041704060257;9573.712788616353;9586.376885502421;9730;9857.852132218166;11145.498452569816;12902.67743306425;13094;13137.540186093442;13589.1846489207 18:42:14 INFO - PROCESS | 3417 | |3;mootools.html;1457.6647371104646;1519.4529969211085;1710.1278348042501;1860.246600126949;1860.516265770899;9204.263658907288;9906.554205060771;9911.950440247789;9917.553710083046;9921.851172232404;8255.793605159859;9201.447913125201;9465.100815422542;9519.095685909844;9519.095685909844;2445.902163913441;2491.9252422427357;2496.3135137837057;2499.6500489931423;2502.862342571158;2408.602580574212;2563.6923569171727;2565.8203925725206;2565.8332208406396;2568.666073410454;2522.3946252899354;2525.6716626838484;2559.1298958354155;2560.1039636127393;2560.1679454177374;2467.037855236459;2484.503099380131;2495.0643508684243;2503.662005629235;2518.7859031982302;2418.4195793009726;2467.2721547143638;2468.8518688878726;2471.7033955925226;2486.651868738378;9506.191973682246;9557.569909354026;9653.806923861483;9784;9788;5637.182608521742;5766.509846663039;5810.099434587646;5817.970910145465;5818.854528636818;3547.875824346161;3643.890683279506;3713.628637136265;3722.236941427001;3731.8880433587037;1115.9621551956677;1216.9087318451152;1217.5312504685671;1218.244688293255;1247.0647014738945;14138.646533836634;14209.715805683933;14217.644558886008;14241.00312978092;14259.429622815074;4955.35580374549;5050.84847454577;5117.565006974374;5147.871303217375;5186.870328241749;11741.706457338634;11756.05951523876;11775.293482390913;11788.646340609796;11789.174757766874;5807.390224026501;5815.621984570989;5820.708964551755;5852.824415267549;5859.853503662443;4732.455767586772;4814.15752243356;5265.6050796189675;5344.385395679469;5361.383440904346;4364.367166760802;4378.452693413324;4422.71252368595;4453.19842428366;4465.620422264079;1332.247280286635;1342.8992825538123;1354.8848347890344;1370.766969615148;1383.2184815579164;995.5918073589938;1026.7484466305652;1048.365738728074;1049.1134990932649;1067.572970811666;6490.247633139026;6642.402183803384;6706.161729783777;6825.624590647462;6828.112345395067;483.65177072507737;486.29972839111565;490.99263511047366;499.08168969097034;511.8003978448408;4580.129775342674;4594.218982772936;4643.3963584733765;4678.976605116953;4686.882827929261;12280.815787763191;12460.439280232411;12593.685157871127;12626.684332891751;12637.810432843515;11557.537698491966;12557.309347985765;12610.936945315398;12616;12656.987441004698;8836.469811811312;9056.954715226513;9060.501672407949;9067.81864362709;9079.455232685928;6691;6749.0888730020815;6829.52193346461;6905.205901321211;7048.541844780073;11046.171537134587;11406.600768973047;11514.30914145154;11522.53909843597;11548.48031838586;10935.343879367263;11782;11791.587294444997;11845.46695398692;11944.462499187732;7821.921780782347;8506.319494440553;8794.956025220088;8801.163889430494;8960.551972401485;9081.091890810865;9479.194268487256;9480.668176613786;9486.667966621135;9490.28822838276;8206.22040906113;8248.83502329962;8260.710875119343;8340.165983401612;8403.83192336162;8020.197980202167;8037.557934313669;8097.433179677366;8243.093259741543;8432.915670843213;7135.250798666273;7182.928170718435;7468.4398670101045;7586.089669239726;7657.846843062996;5865.384134665969;5951.345352011362;6015.308239552507;6048.213732214784;6050;5851.8244452664785;5919.526437884872;5945.464908158287;5994.580379373402;6015.097735339734 18:42:14 INFO - PROCESS | 3417 | |4;prototype.html;358.32992304390785;360.3819449643861;362.8675533430299;363.66542780641805;365.23483302481293;5447.1829225616175;5473.4526547345295;5485.478879506445;5495.917561236574;5586.55307575394;5408.2698835657175;5417.483104730672;5436.75534600943;5449.455054494549;5482.23248745176;4146.502419709639;4260.70175087744;4276.807543660527;4298.548652391501;4302.440682711243;4276.631477927064;4286.549912259215;4288.292431748758;4305.655547556204;4330.740155590659;4280.486341639008;4282.507511636155;4283.657307415415;4287.363271587982;4295.377170310303;4173.749575025493;4217.894552636178;4252.085801552681;4311.698181127323;4317.8989357713735;3760.830762615689;3765.152840610868;3767.397216445383;3773.622637736232;3790.8104594770152;5307.416184219733;5313.628046036779;5342.893142137174;5346.946530534723;5360.892782144374;4731.455882573514;4826.879328016793;4887.413510378742;4900.558949694545;4912.533309335608;5563.805266815682;5565.94247093051;5583.804566840141;5586.190002449622;5588.133839254922;4144.5026596808275;4144.834206631731;4152.958470415318;4159.646430053449;4179.226843034048;5671.602987790856;5831.679257640828;5838.678872662044;5841.85395365115;5887.175795388649;4248.426462427533;4248.957510424918;4257.87226383209;4268.146370725867;4308.159908817751;4334.461266250499;4343.3484977253665;4355.8475453359615;4360.171567402183;4369.934450983238;4653.930191047137;4874.268859671007;4911.582515486152;4919.262110683355;4924.089043526959;5633.492985631313;5649.858753531194;5663.631863928831;5675.943240567542;5677.2619559457835;1470.9264536773119;1501.5495351394538;1503.3460444706368;1506.7212565675386;1507.9698406031819;930.02812061396;964.6189755046718;985.2659768472533;985.975350616226;987.1855719031828;4967.428745694291;4986.6010719143105;4998.375203099613;5013.54878060969;5017.949820501748;5191.442567229816;5199.948000519947;5205.323307970016;5209.947900521022;5235.534050465875;4983.227599721977;4987.875303117378;4988.6507944444265;5216.973915130476;5578.633234857486;3120.4695201815293;3125.8280794556063;3126.499760038383;3128.311771410286;3147.937041259208;6069.332373438918;6084.361142080018;6110.511159107261;6137.110119032715;6139.877202455926;5703.431556322044;5735.2257445245195;5973.49225315853;6022.277326720734;6025.487833534199;5872.853178670567;6047.758089676364;6104.786332478431;6121.84695382619;6126.663033533109;5855.619384739978;5855.795047173328;5871.647701137946;5876.382979787061;5876.500497457764;5629.718514074362;5823.475887170175;5853.151293062568;5865.853353666193;5867.647941123546;6022.909656355246;6127.754889804358;6136.23297087864;6137.263528376623;6155.507559395148;4110.712250142461;4315.978420107879;4345.348197770233;4353.934690979574;4368.2355587772645;5776.133579963039;5821.534277257894;5822.184894114743;5829.15477255804;5835.67903765297;5658.490735833795;5672.815965853094;5680.375158732453;5683.886322273449;5709.800156994485;5600.7101307692765;5646.237757902631;5721.370649228665;5741.339745929272;5752.741126649395;5618.909599720219;5653.830385088289;5659.924614323266;5673.404292549388;5711.314642242957;5572.3034620672415;5686.77252909879;5713.8285851424525;5733.197352370755;5751.33859606134;5598.468145526169;5626.381098079126;5633.746481408429;5640.435956404327;5654.773809047592 18:42:14 INFO - PROCESS | 3417 | |5;yui.html;828.6643909216762;846.8306338732256;892.8437523433399;895.6820328783282;927.3137877970299;16045;16115.919420402895;16676.916615416907;17018.82981170188;17039;16378.181090945465;17303.13484325785;17329.220185091668;17331.480055598353;17348.306067757276;733.9376153026999;854.2610641794865;855.3328403845011;881.7310720230328;886.8403687336277;853.4793775796759;857.8027053777635;858.1932982995995;858.5664239559014;868.6004437958546;905.6603773584906;905.6875377994581;906.1346414174448;906.5150144672601;906.768773962642;904.886889138861;905.1355955062899;905.180811365715;905.3662436294587;906.3519583497798;659.4394764450227;659.4856012310405;660.1253339325413;660.1978596005875;660.1978596005875;15670.373185072698;15724;15726.05643661384;15729.842701573067;15752.921235393864;16698.499045028668;16754.916225418918;16757.49727508177;16839.74740378895;17184.484465466056;13035.608931732064;13218.735625287536;13277;13474.865251347459;13568.93215533916;4143.875683729493;4214.683898707609;4226.809793559297;4280.122574872144;4329.631981281595;60807;85405;91212.54393727989;92965.07034929632;93759.53120234492;13024.47902083925;13040.282784446757;13074.411651475706;13241.404136813866;13330.533431329857;22304;22469.662955055686;22569.66145507819;22641.094356225567;22652.320430387113;8783.648654053894;8874.156955089386;9026.503542305236;9231.856223069197;9268.95365523168;5051.444341122474;5080.415752188472;5089.312942752755;5111.974440127776;5336.226247194135;3891.902702432405;3987.2623564640635;3998.0804414984136;4008.198360327946;4334.371516130143;769.6305773228881;771.4484143837184;779.0884664942031;779.1195948578184;791.5290402210676;746.268656716421;774.9457537972401;782.9686812527549;785.5169071021281;788.6805843633338;9252.908878273145;9991.500424978722;10424.843627345595;10441.947790261152;10516;702.5047341624143;731.608589404666;761.2501685839444;772.7140957845633;831.9126491718404;4802.951970480251;5085.771140298695;5130.384353877484;5186.896262074737;5187.0922588546855;74123;74125.62937185279;74635;74849;112160;102999.9999999985;104791;104973;107842;107980;88492.5575372119;94504.52747736355;94712.10575788446;95119;95220.04779952111;9379.437233765995;9619.90380096218;9744.805103897741;9796;9827.557759900965;36613.450798237514;38510;43443.782781085894;45351.77324113358;54252.016139112726;74723;77613;80355;81444.59277703811;81588.59205703934;12953;13276.601701948957;13471.271433033051;13558.932205339304;13693.589192324247;13881.444742210602;14015.649608759863;14474.782878256625;14753.114813111248;14988.925055374653;12534.122611417113;12667;13612.387442565307;13919.512817051354;14735.92632036833;13505.864941350854;13713.588592342247;13839.169649821042;13895.930520347334;14210.644733881736;13731.794023089862;13754.449822007007;13858.861411385757;13862.792058119338;14244.646758557921;11493.310401375944;11848.822267665819;12199.817002745143;12447.004239660986;12706.237625742486;11549.36478493691;11701.88298117008;11754.033757156802;11806.350650714294;11991.520339186334 18:42:14 INFO - PROCESS | 3417 | __end_tp_report 18:42:14 INFO - PROCESS | 3417 | __start_cc_report 18:42:14 INFO - PROCESS | 3417 | _x_x_mozilla_cycle_collect,1006 18:42:14 INFO - PROCESS | 3417 | __end_cc_report 18:42:14 INFO - PROCESS | 3417 | __startTimestamp1477014134436__endTimestamp 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | ------- Summary: start ------- 18:42:14 INFO - PROCESS | 3417 | Number of tests: 6 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#0] dojo.html Cycles:165 Average:5641.89 Median:5834.24 stddev:636.87 (10.9%) stddev-sans-first:610.80 18:42:14 INFO - PROCESS | 3417 | Values: 3260.4 3262.2 3279.6 3292.7 3315.7 5545.5 5602.8 5640.1 5644.4 5670.3 5654.0 5704.8 5731.5 5737.6 5737.8 5174.8 5177.9 5183.6 5236.4 5248.6 4985.0 5022.5 5238.1 5250.4 5276.6 5102.3 5129.2 5130.8 5171.7 5203.1 5195.9 5227.8 5232.4 5234.1 5271.2 4989.9 5110.8 5193.9 5211.9 5220.9 5557.2 5576.9 5620.2 5649.2 5696.5 6058.3 6088.9 6106.5 6110.1 6111.8 5786.3 6147.3 6151.1 6154.0 6160.9 5588.0 5630.3 5652.9 5670.8 5705.1 6249.7 6249.9 6260.8 6261.9 6271.5 5723.0 5918.7 5976.7 5977.9 5981.0 6022.3 6070.1 6077.8 6122.9 6124.2 6066.1 6095.6 6097.3 6106.5 6140.4 5790.6 6122.8 6124.1 6127.1 6136.8 4546.9 4632.7 4649.2 4655.4 4717.2 4171.4 4177.9 4180.4 4182.5 4209.7 5737.4 5787.2 5793.3 5795.4 5834.9 5831.1 5958.0 5978.9 6023.5 6120.3 5754.7 5757.5 5759.7 5765.0 5769.7 5240.1 5284.8 5286.5 5298.4 5301.2 6041.3 6183.9 6219.8 6229.2 6351.9 6110.2 6115.4 6119.5 6123.5 6128.0 6335.7 6344.2 6354.9 6358.6 6361.3 5737.2 5865.8 5898.2 5919.2 6007.4 6006.5 6052.5 6070.0 6070.3 6073.8 5952.6 5953.6 5964.2 5965.3 5967.6 5735.0 5857.3 5873.8 5917.1 6026.5 5947.4 5960.0 5961.7 5963.5 5968.9 5693.4 5945.8 5979.4 5996.6 6004.3 5732.4 5834.2 5842.4 5886.0 5886.2 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#1] ext.html Cycles:180 Average:69325.33 Median:41432.71 stddev:142364.08 (343.6%) stddev-sans-first:142669.90 18:42:14 INFO - PROCESS | 3417 | Values: 586.4 644.9 647.8 656.2 659.8 34749.7 47009.3 47918.5 47972.8 48055.8 48978.8 48997.8 49240.3 49476.0 49668.5 8545.1 9304.9 9434.2 9461.1 11157.6 10954.6 11436.4 11459.4 11461.3 11463.5 11564.4 11609.0 11612.9 11613.1 11630.7 11518.8 11559.5 11564.9 11572.6 11581.4 11287.9 11303.3 11365.0 11388.2 11393.2 46036.0 46774.3 46779.5 46781.8 46800.3 3590.3 4220.6 4238.8 4242.7 4550.9 35468.3 35778.3 36313.3 36739.3 37525.8 4541.8 4566.0 4599.5 4655.6 4854.2 376036.0 377518.0 377617.0 378387.0 378460.0 39227.4 66471.3 66763.0 66825.0 66906.0 39670.4 41693.0 41764.6 41827.4 41847.2 17687.8 17762.7 17890.6 17934.8 17935.2 13589.6 13950.9 13951.7 14212.3 14437.5 9349.0 9469.6 9594.2 9596.1 9601.5 3589.8 3592.6 3604.0 3663.3 3665.2 1079.5 1144.3 1157.5 1159.8 1176.2 35143.5 35463.5 35760.0 35893.6 35919.1 1171.5 1202.5 1246.0 1276.3 1285.3 16311.4 18621.7 18780.0 19444.2 19480.6 628076.0 632229.0 633829.0 634561.0 635628.0 66207.7 66607.3 66622.7 66722.0 66761.0 1322.0 1425.7 1431.9 1444.1 1470.6 32951.3 36049.2 36619.3 37356.0 37463.4 57895.4 60543.0 62735.4 62783.0 69454.7 551972.0 562785.0 563175.0 564451.0 564772.0 37253.0 39996.4 40320.0 50252.5 50406.0 53376.7 53617.5 53636.5 54028.7 54841.2 45621.1 46246.5 46679.3 47135.5 47930.5 46507.0 47153.8 47258.3 47545.8 48116.8 45504.0 46320.5 46511.1 46542.5 48577.5 38507.8 40660.4 41274.8 41590.6 42208.6 31325.6 31335.5 31371.4 31507.2 31684.8 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#2] jquery.html Cycles:180 Average:31809.20 Median:14020.51 stddev:92730.44 (661.4%) stddev-sans-first:92960.64 18:42:14 INFO - PROCESS | 3417 | Values: 430.0 432.8 435.4 441.4 446.5 19612.8 20986.7 21569.2 21673.6 21854.6 20314.0 21084.8 21210.7 21285.5 21410.4 5770.6 5974.6 6040.6 6041.0 6045.6 5733.7 5846.2 5870.4 5885.1 5888.8 5852.8 5871.9 5877.5 5880.9 5886.7 5762.1 5820.8 5873.1 5878.8 5888.5 5294.7 5307.8 5319.1 5325.7 5327.9 24794.8 25339.2 25346.1 25382.6 25498.0 2504.5 2543.0 2575.3 2619.4 2809.3 14786.2 15710.8 15738.4 15833.8 16221.2 1961.3 1970.6 2061.6 2063.3 2093.3 59217.9 64625.0 70617.8 70836.6 76492.6 11278.7 11397.5 11462.5 11593.1 11999.8 15969.2 15971.3 15988.3 15990.0 15990.8 8690.2 8826.6 8986.2 9019.0 9058.1 8949.0 8953.8 9003.6 9019.0 9235.7 4773.8 4788.1 4811.0 5219.3 5275.6 2112.4 2133.6 2164.4 2177.2 2201.3 810.9 910.9 930.9 1009.8 1095.6 20727.9 21278.6 21359.3 21719.2 21778.0 991.8 1004.4 1010.8 1020.0 1037.3 9775.8 11665.0 11792.4 11853.8 11944.0 558386.0 558582.0 565059.0 571882.0 573736.0 91326.5 91561.5 94592.0 94680.0 100796.5 1046.8 1074.1 1096.3 1096.4 1098.4 18666.5 19668.3 19697.3 19810.8 19843.2 44521.8 45155.5 45325.0 45895.3 47961.3 63257.0 63531.0 64680.0 65148.7 65711.3 7906.5 8111.3 8227.9 8388.5 8952.6 11048.3 12144.0 14078.7 14495.1 14630.3 12432.2 12730.7 12951.5 12970.2 13162.3 12015.8 12694.6 12853.8 13100.5 13151.8 6706.4 12768.2 13050.8 13085.7 13260.1 9383.0 9573.7 9586.4 9730.0 9857.9 11145.5 12902.7 13094.0 13137.5 13589.2 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#3] mootools.html Cycles:180 Average:6437.55 Median:2648.90 stddev:3769.75 (142.3%) stddev-sans-first:3761.75 18:42:14 INFO - PROCESS | 3417 | Values: 1457.7 1519.5 1710.1 1860.2 1860.5 9204.3 9906.6 9912.0 9917.6 9921.9 8255.8 9201.4 9465.1 9519.1 9519.1 2445.9 2491.9 2496.3 2499.7 2502.9 2408.6 2563.7 2565.8 2565.8 2568.7 2522.4 2525.7 2559.1 2560.1 2560.2 2467.0 2484.5 2495.1 2503.7 2518.8 2418.4 2467.3 2468.9 2471.7 2486.7 9506.2 9557.6 9653.8 9784.0 9788.0 5637.2 5766.5 5810.1 5818.0 5818.9 3547.9 3643.9 3713.6 3722.2 3731.9 1116.0 1216.9 1217.5 1218.2 1247.1 14138.6 14209.7 14217.6 14241.0 14259.4 4955.4 5050.8 5117.6 5147.9 5186.9 11741.7 11756.1 11775.3 11788.6 11789.2 5807.4 5815.6 5820.7 5852.8 5859.9 4732.5 4814.2 5265.6 5344.4 5361.4 4364.4 4378.5 4422.7 4453.2 4465.6 1332.2 1342.9 1354.9 1370.8 1383.2 995.6 1026.7 1048.4 1049.1 1067.6 6490.2 6642.4 6706.2 6825.6 6828.1 483.7 486.3 491.0 499.1 511.8 4580.1 4594.2 4643.4 4679.0 4686.9 12280.8 12460.4 12593.7 12626.7 12637.8 11557.5 12557.3 12610.9 12616.0 12657.0 8836.5 9057.0 9060.5 9067.8 9079.5 6691.0 6749.1 6829.5 6905.2 7048.5 11046.2 11406.6 11514.3 11522.5 11548.5 10935.3 11782.0 11791.6 11845.5 11944.5 7821.9 8506.3 8795.0 8801.2 8960.6 9081.1 9479.2 9480.7 9486.7 9490.3 8206.2 8248.8 8260.7 8340.2 8403.8 8020.2 8037.6 8097.4 8243.1 8432.9 7135.3 7182.9 7468.4 7586.1 7657.8 5865.4 5951.3 6015.3 6048.2 6050.0 5851.8 5919.5 5945.5 5994.6 6015.1 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#4] prototype.html Cycles:180 Average:4786.42 Median:5427.12 stddev:1384.25 (25.5%) stddev-sans-first:1347.65 18:42:14 INFO - PROCESS | 3417 | Values: 358.3 360.4 362.9 363.7 365.2 5447.2 5473.5 5485.5 5495.9 5586.6 5408.3 5417.5 5436.8 5449.5 5482.2 4146.5 4260.7 4276.8 4298.5 4302.4 4276.6 4286.5 4288.3 4305.7 4330.7 4280.5 4282.5 4283.7 4287.4 4295.4 4173.7 4217.9 4252.1 4311.7 4317.9 3760.8 3765.2 3767.4 3773.6 3790.8 5307.4 5313.6 5342.9 5346.9 5360.9 4731.5 4826.9 4887.4 4900.6 4912.5 5563.8 5565.9 5583.8 5586.2 5588.1 4144.5 4144.8 4153.0 4159.6 4179.2 5671.6 5831.7 5838.7 5841.9 5887.2 4248.4 4249.0 4257.9 4268.1 4308.2 4334.5 4343.3 4355.8 4360.2 4369.9 4653.9 4874.3 4911.6 4919.3 4924.1 5633.5 5649.9 5663.6 5675.9 5677.3 1470.9 1501.5 1503.3 1506.7 1508.0 930.0 964.6 985.3 986.0 987.2 4967.4 4986.6 4998.4 5013.5 5017.9 5191.4 5199.9 5205.3 5209.9 5235.5 4983.2 4987.9 4988.7 5217.0 5578.6 3120.5 3125.8 3126.5 3128.3 3147.9 6069.3 6084.4 6110.5 6137.1 6139.9 5703.4 5735.2 5973.5 6022.3 6025.5 5872.9 6047.8 6104.8 6121.8 6126.7 5855.6 5855.8 5871.6 5876.4 5876.5 5629.7 5823.5 5853.2 5865.9 5867.6 6022.9 6127.8 6136.2 6137.3 6155.5 4110.7 4316.0 4345.3 4353.9 4368.2 5776.1 5821.5 5822.2 5829.2 5835.7 5658.5 5672.8 5680.4 5683.9 5709.8 5600.7 5646.2 5721.4 5741.3 5752.7 5618.9 5653.8 5659.9 5673.4 5711.3 5572.3 5686.8 5713.8 5733.2 5751.3 5598.5 5626.4 5633.7 5640.4 5654.8 18:42:14 INFO - PROCESS | 3417 | 18:42:14 INFO - PROCESS | 3417 | [#5] yui.html Cycles:180 Average:20883.64 Median:23889.08 stddev:29105.66 (121.8%) stddev-sans-first:29148.35 18:42:14 INFO - PROCESS | 3417 | Values: 828.7 846.8 892.8 895.7 927.3 16045.0 16115.9 16676.9 17018.8 17039.0 16378.2 17303.1 17329.2 17331.5 17348.3 733.9 854.3 855.3 881.7 886.8 853.5 857.8 858.2 858.6 868.6 905.7 905.7 906.1 906.5 906.8 904.9 905.1 905.2 905.4 906.4 659.4 659.5 660.1 660.2 660.2 15670.4 15724.0 15726.1 15729.8 15752.9 16698.5 16754.9 16757.5 16839.7 17184.5 13035.6 13218.7 13277.0 13474.9 13568.9 4143.9 4214.7 4226.8 4280.1 4329.6 60807.0 85405.0 91212.5 92965.1 93759.5 13024.5 13040.3 13074.4 13241.4 13330.5 22304.0 22469.7 22569.7 22641.1 22652.3 8783.6 8874.2 9026.5 9231.9 9269.0 5051.4 5080.4 5089.3 5112.0 5336.2 3891.9 3987.3 3998.1 4008.2 4334.4 769.6 771.4 779.1 779.1 791.5 746.3 774.9 783.0 785.5 788.7 9252.9 9991.5 10424.8 10441.9 10516.0 702.5 731.6 761.3 772.7 831.9 4803.0 5085.8 5130.4 5186.9 5187.1 74123.0 74125.6 74635.0 74849.0 112160.0 103000.0 104791.0 104973.0 107842.0 107980.0 88492.6 94504.5 94712.1 95119.0 95220.0 9379.4 9619.9 9744.8 9796.0 9827.6 36613.5 38510.0 43443.8 45351.8 54252.0 74723.0 77613.0 80355.0 81444.6 81588.6 12953.0 13276.6 13471.3 13558.9 13693.6 13881.4 14015.6 14474.8 14753.1 14988.9 12534.1 12667.0 13612.4 13919.5 14735.9 13505.9 13713.6 13839.2 13895.9 14210.6 13731.8 13754.4 13858.9 13862.8 14244.6 11493.3 11848.8 12199.8 12447.0 12706.2 11549.4 11701.9 11754.0 11806.4 11991.5 18:42:14 INFO - PROCESS | 3417 | -------- Summary: end -------- 18:42:14 INFO - PROCESS | 3417 | 18:42:15 INFO - TEST-INFO | 3417: exit 0 18:42:15 INFO - TEST-OK | dromaeo_css | took 1114299ms 18:42:15 INFO - TEST-START | kraken 18:42:15 INFO - operating with platform_type : linux_ 18:42:15 INFO - Initialising browser for kraken test... 18:42:15 INFO - TEST-INFO | started process 3610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnfR4ou/profile http://localhost:57777/getInfo.html) 18:42:19 INFO - PROCESS | 3610 | __metrics Screen width/height:1600/1200 18:42:19 INFO - PROCESS | 3610 | colorDepth:24 18:42:19 INFO - PROCESS | 3610 | Browser inner width/height: 1024/697 18:42:19 INFO - PROCESS | 3610 | __metrics 18:42:20 INFO - PROCESS | 3610 | 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" 18:42:20 INFO - PROCESS | 3610 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:21 INFO - PROCESS | 3610 | console.error: PushService: 18:42:21 INFO - PROCESS | 3610 | stateChangeProcessEnqueue: Error transitioning state 18:42:21 INFO - PROCESS | 3610 | UnknownError 18:42:21 INFO - TEST-INFO | 3610: exit 0 18:42:21 INFO - Browser initialized. 18:42:21 INFO - Running cycle 1/1 for kraken test... 18:42:21 INFO - TEST-INFO | started process 3699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnfR4ou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:33 INFO - PROCESS | 3699 | RSS: Main: 143867904 18:42:33 INFO - PROCESS | 3699 | 18:42:35 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57777/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:36 INFO - PROCESS | 3699 | RSS: Main: 199180288 18:42:36 INFO - PROCESS | 3699 | 18:42:39 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57777/tests/kraken/driver.html?testName=audio-dft) 18:42:39 INFO - PROCESS | 3699 | RSS: Main: 183947264 18:42:39 INFO - PROCESS | 3699 | 18:42:42 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57777/tests/kraken/driver.html?testName=audio-fft) 18:42:43 INFO - PROCESS | 3699 | RSS: Main: 275365888 18:42:43 INFO - PROCESS | 3699 | 18:42:45 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57777/tests/kraken/driver.html?testName=audio-oscillator) 18:42:45 INFO - PROCESS | 3699 | RSS: Main: 180117504 18:42:45 INFO - PROCESS | 3699 | 18:42:48 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57777/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:48 INFO - PROCESS | 3699 | RSS: Main: 179003392 18:42:48 INFO - PROCESS | 3699 | 18:42:51 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57777/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:52 INFO - PROCESS | 3699 | RSS: Main: 214822912 18:42:52 INFO - PROCESS | 3699 | 18:42:55 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57777/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:56 INFO - PROCESS | 3699 | RSS: Main: 214077440 18:42:56 INFO - PROCESS | 3699 | 18:42:58 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57777/tests/kraken/driver.html?testName=json-parse-financial) 18:43:00 INFO - PROCESS | 3699 | RSS: Main: 213995520 18:43:00 INFO - PROCESS | 3699 | 18:43:01 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57777/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:43:01 INFO - PROCESS | 3699 | RSS: Main: 175742976 18:43:01 INFO - PROCESS | 3699 | 18:43:03 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:43:03 INFO - PROCESS | 3699 | RSS: Main: 182333440 18:43:03 INFO - PROCESS | 3699 | 18:43:06 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:06 INFO - PROCESS | 3699 | RSS: Main: 189194240 18:43:06 INFO - PROCESS | 3699 | 18:43:08 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:43:09 INFO - PROCESS | 3699 | RSS: Main: 198041600 18:43:09 INFO - PROCESS | 3699 | 18:43:12 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:43:12 INFO - PROCESS | 3699 | RSS: Main: 193556480 18:43:12 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | Cycle 1(1): loaded http://localhost:57777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57777/tests/kraken/driver.html?testName=ai-astar) 18:43:14 INFO - PROCESS | 3699 | RSS: Main: 244862976 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | __start_tp_report 18:43:14 INFO - PROCESS | 3699 | _x_x_mozilla_page_load 18:43:14 INFO - PROCESS | 3699 | _x_x_mozilla_page_load_details 18:43:14 INFO - PROCESS | 3699 | |i|pagename|runs| 18:43:14 INFO - PROCESS | 3699 | |0;ai-astar;111;110;121;111;110;111;109;112;112;113 18:43:14 INFO - PROCESS | 3699 | |1;audio-beat-detection;146;115;143;182;119;146;149;121;170;115 18:43:14 INFO - PROCESS | 3699 | |2;audio-dft;160;160;161;160;162;161;160;161;160;160 18:43:14 INFO - PROCESS | 3699 | |3;audio-fft;79;75;75;175;76;76;76;75;174;76 18:43:14 INFO - PROCESS | 3699 | |4;audio-oscillator;84;82;158;84;83;84;148;83;82;144 18:43:14 INFO - PROCESS | 3699 | |5;imaging-gaussian-blur;116;116;117;117;116;117;116;116;117;117 18:43:14 INFO - PROCESS | 3699 | |6;imaging-darkroom;146;146;145;146;146;146;145;146;146;145 18:43:14 INFO - PROCESS | 3699 | |7;imaging-desaturate;95;94;94;95;94;95;95;95;94;95 18:43:14 INFO - PROCESS | 3699 | |8;json-parse-financial;50;49;52;52;51;52;50;51;50;50 18:43:14 INFO - PROCESS | 3699 | |9;json-stringify-tinderbox;59;59;59;60;61;59;60;59;60;59 18:43:14 INFO - PROCESS | 3699 | |10;stanford-crypto-aes;79;73;74;73;80;72;73;76;75;72 18:43:14 INFO - PROCESS | 3699 | |11;stanford-crypto-ccm;120;129;124;120;118;128;120;120;151;125 18:43:14 INFO - PROCESS | 3699 | |12;stanford-crypto-pbkdf2;151;151;151;151;151;153;151;152;152;150 18:43:14 INFO - PROCESS | 3699 | |13;stanford-crypto-sha256-iterative;61;61;61;64;62;63;60;59;61;59 18:43:14 INFO - PROCESS | 3699 | __end_tp_report 18:43:14 INFO - PROCESS | 3699 | __start_cc_report 18:43:14 INFO - PROCESS | 3699 | _x_x_mozilla_cycle_collect,1686 18:43:14 INFO - PROCESS | 3699 | __end_cc_report 18:43:14 INFO - PROCESS | 3699 | __startTimestamp1477014194722__endTimestamp 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | ------- Summary: start ------- 18:43:14 INFO - PROCESS | 3699 | Number of tests: 14 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#0] ai-astar Cycles:10 Average:112.00 Median:111.50 stddev:3.37 (3.0%) stddev-sans-first:3.55 18:43:14 INFO - PROCESS | 3699 | Values: 111.0 110.0 121.0 111.0 110.0 111.0 109.0 112.0 112.0 113.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.00 stddev:23.27 (15.9%) stddev-sans-first:24.60 18:43:14 INFO - PROCESS | 3699 | Values: 146.0 115.0 143.0 182.0 119.0 146.0 149.0 121.0 170.0 115.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 18:43:14 INFO - PROCESS | 3699 | Values: 160.0 160.0 161.0 160.0 162.0 161.0 160.0 161.0 160.0 160.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#3] audio-fft Cycles:10 Average:95.70 Median:76.00 stddev:41.55 (54.7%) stddev-sans-first:43.63 18:43:14 INFO - PROCESS | 3699 | Values: 79.0 75.0 75.0 175.0 76.0 76.0 76.0 75.0 174.0 76.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:83.00 stddev:32.48 (39.1%) stddev-sans-first:33.70 18:43:14 INFO - PROCESS | 3699 | Values: 84.0 82.0 158.0 84.0 83.0 84.0 148.0 83.0 82.0 144.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 18:43:14 INFO - PROCESS | 3699 | Values: 116.0 116.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 18:43:14 INFO - PROCESS | 3699 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:43:14 INFO - PROCESS | 3699 | Values: 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.06 (2.1%) stddev-sans-first:1.09 18:43:14 INFO - PROCESS | 3699 | Values: 50.0 49.0 52.0 52.0 51.0 52.0 50.0 51.0 50.0 50.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 18:43:14 INFO - PROCESS | 3699 | Values: 59.0 59.0 59.0 60.0 61.0 59.0 60.0 59.0 60.0 59.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:2.83 (3.8%) stddev-sans-first:2.54 18:43:14 INFO - PROCESS | 3699 | Values: 79.0 73.0 74.0 73.0 80.0 72.0 73.0 76.0 75.0 72.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:124.50 stddev:9.71 (7.8%) stddev-sans-first:10.09 18:43:14 INFO - PROCESS | 3699 | Values: 120.0 129.0 124.0 120.0 118.0 128.0 120.0 120.0 151.0 125.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 18:43:14 INFO - PROCESS | 3699 | Values: 151.0 151.0 151.0 151.0 151.0 153.0 151.0 152.0 152.0 150.0 18:43:14 INFO - PROCESS | 3699 | 18:43:14 INFO - PROCESS | 3699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.60 (2.6%) stddev-sans-first:1.69 18:43:14 INFO - PROCESS | 3699 | Values: 61.0 61.0 61.0 64.0 62.0 63.0 60.0 59.0 61.0 59.0 18:43:14 INFO - PROCESS | 3699 | -------- Summary: end -------- 18:43:14 INFO - PROCESS | 3699 | 18:43:15 INFO - TEST-INFO | 3699: exit 0 18:43:15 INFO - TEST-OK | kraken | took 60546ms 18:43:15 INFO - SUITE-END | took 1174s 18:43:15 INFO - Completed test suite (00:19:34) 18:43:15 INFO - javascript benchmark 18:43:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.4457242268813, 3262.2333751568385, 3279.55725976993, 3292.670732926709, 3315.7015868571825, 5545.528630066444, 5602.7758889644465, 5640.069388550889, 5644.379118296994, 5670.319561652607, 5653.982283189024, 5704.828855134342, 5731.484166425021, 5737.627054241471, 5737.827865164041, 5174.818881339153, 5177.870553236171, 5183.637145399824, 5236.371635403738, 5248.5538729208065, 4984.950150498503, 5022.472640372763, 5238.083335416306, 5250.422453530127, 5276.604254680895, 5102.260172275018, 5129.204973229155, 5130.820421285256, 5171.663841850286, 5203.115470370028, 5195.87010324743, 5227.764750586232, 5232.424433312332, 5234.136367499358, 5271.182966640177, 4989.850304490871, 5110.770015349281, 5193.922091168635, 5211.947880521184, 5220.92168617471, 5557.194206840026, 5576.944230557683, 5620.185073164368, 5649.180868774005, 5696.487316141567, 6058.273007239115, 6088.878222435571, 6106.48094911933, 6110.144579758838, 6111.816645500642, 5786.334571524287, 6147.262328520562, 6151.108089327067, 6153.969230153865, 6160.8767824643255, 5587.972060139714, 5630.268065151545, 5652.85867853307, 5670.829875103754, 5705.058665320175, 6249.6562689051625, 6249.906251406142, 6260.780872669435, 6261.906071408933, 6271.498280137578, 5723.027085395493, 5918.733656985446, 5976.731047102804, 5977.880442391215, 5981.0, 6022.337542870281, 6070.119832624245, 6077.756889724362, 6122.855605810045, 6124.228368151265, 6066.090086487062, 6095.6037857540005, 6097.3292937776805, 6106.542009349317, 6140.4473597376455, 5790.623609465456, 6122.785702500391, 6124.050772130327, 6127.050307202391, 6136.815895523141, 4546.909061818745, 4632.652551058684, 4649.18639238132, 4655.4180727409075, 4717.150912835654, 4171.436856024458, 4177.874663760092, 4180.393842892763, 4182.53992060873, 4209.663226941899, 5737.3975732548315, 5787.247657804459, 5793.333766616893, 5795.392073566912, 5834.883302333929, 5831.125331200354, 5957.970210148921, 5978.880422391528, 6023.548233882389, 6120.296165940974, 5754.712264386764, 5757.453041961007, 5759.6832174229985, 5765.0, 5769.740361683735, 5240.135377662643, 5284.7886084556185, 5286.524212820865, 5298.3906850712665, 5301.231321458367, 6041.2750469943885, 6183.938160618336, 6219.813405597839, 6229.1590635264565, 6351.872962540631, 6110.205673262448, 6115.388461153849, 6119.510439164767, 6123.540734445023, 6127.969360153171, 6335.651539165206, 6344.175257216532, 6354.936450635434, 6358.586691865014, 6361.300256971822, 5737.196792449144, 5865.765369385177, 5898.203742494708, 5919.200907877382, 6007.429294217042, 6006.549508786771, 6052.485538729257, 6070.0, 6070.3019152798, 6073.817785466443, 5952.5833191676165, 5953.553483488842, 5964.165016897725, 5965.28416590012, 5967.552433567413, 5735.0, 5857.326407463196, 5873.794417195549, 5917.142014407973, 6026.548008899263, 5947.375525569752, 5960.046392577166, 5961.731722072431, 5963.463288304073, 5968.880622387441, 5693.430656934439, 5945.821625351246, 5979.40205979416, 5996.580239383201, 6004.309504406874, 5732.369439361751, 5834.241548598655, 5842.357340692605, 5886.0, 5886.205362276038], "value": 5599.245907243403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.4282324733385, 644.8645784385279, 647.757091090841, 656.2158220925992, 659.7558903205814, 34749.65250347496, 47009.294860577116, 47918.520814791926, 47972.76013619936, 48055.75972120143, 48978.75510622451, 48997.75501122498, 49240.261396079, 49476.0, 49668.50331496684, 8545.145485451467, 9304.906950930488, 9434.150926416656, 9461.053894610553, 11157.609483668073, 10954.616588419407, 11436.37099959502, 11459.427028648575, 11461.255018423775, 11463.541458341657, 11564.363959982198, 11609.0, 11612.883871161264, 11613.070954323633, 11630.709232269219, 11518.827217591743, 11559.479823407966, 11564.942175289154, 11572.59495917639, 11581.363025033663, 11287.943560282229, 11303.265287756269, 11365.0, 11388.202825802196, 11393.202475826696, 46036.0, 46774.29838552424, 46779.53220467786, 46781.766091169666, 46800.29799553009, 3590.317839610492, 4220.577942205786, 4238.809253583596, 4242.703010789246, 4550.863474095782, 35468.29063418717, 35778.28443431143, 36313.27373452543, 36739.26521469556, 37525.81237093824, 4541.750203738793, 4565.977170114162, 4599.471060828014, 4655.604273636745, 4854.223324268136, 376036.0, 377518.0, 377617.0, 378387.0, 378460.0, 39227.41158882619, 66471.33528664651, 66763.0, 66825.0, 66906.0, 39670.40494392587, 41693.0, 41764.58235417668, 41827.372589410574, 41847.1630567387, 17687.823121768874, 17762.733558996624, 17890.642187156445, 17934.820651793576, 17935.192916318796, 13589.592312230649, 13950.860491395159, 13951.720965580631, 14212.289385530683, 14437.494687685881, 9348.95325523368, 9469.573869175903, 9594.184494317922, 9596.088371604686, 9601.519924003911, 3589.838457269429, 3592.568891733009, 3603.9819800900827, 3663.303972245265, 3665.2303016366864, 1079.4764539198482, 1144.3420033480616, 1157.5196293538088, 1159.8144296912453, 1176.2472017908544, 35143.4728479073, 35463.4680479793, 35760.0, 35893.64106358903, 35919.10202244912, 1171.47869198206, 1202.4528839378063, 1245.9626211213679, 1276.342683517989, 1285.2738202915323, 16311.429099981444, 18621.7206741899, 18780.0, 19444.23623707665, 19480.610387792163, 628076.0, 632229.0, 633829.0, 634561.0, 635628.0, 66207.66896165488, 66607.33392666109, 66622.66688666526, 66722.0, 66761.0, 1321.966950826237, 1425.6720954180596, 1431.9284035798169, 1444.075791493445, 1470.5808844479272, 32951.34097318088, 36049.19754012236, 36619.26761464809, 37356.0, 37463.43804842984, 57895.421045789, 60543.0, 62735.37264627478, 62783.0, 69454.65272673604, 551972.0, 562785.0, 563175.0, 564451.0, 564772.0, 37253.0, 39996.400053999794, 40320.0, 50252.49747502478, 50406.0, 53376.73311633417, 53617.46382536281, 53636.46363536471, 54028.72985635047, 54841.177382340094, 45621.08757824892, 46246.53753462422, 46679.29981050355, 47135.52864471448, 47930.520694792605, 46507.0, 47153.764231178626, 47258.291125633834, 47545.762271188425, 48116.759416202694, 45504.0, 46320.536794631626, 46511.06977860356, 46542.53457465382, 48577.514224857296, 38507.80746096252, 40660.39009414802, 41274.8296469232, 41590.58409415867, 42208.57791422169, 31325.59034843483, 31335.529967050057, 31371.372572547964, 31507.21231969219, 31684.841575791972], "value": 21753.730792223458, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.99355009674844, 432.8398492557752, 435.43828461284943, 441.37765750292095, 446.4776211832156, 19612.803871961292, 20986.685199721996, 21569.24507642231, 21673.566528669417, 21854.562908741857, 20314.0, 21084.789152108475, 21210.681839772416, 21285.467863303424, 21410.35768926931, 5770.567207459447, 5974.641521508724, 6040.57715959883, 6041.033434650457, 6045.576809623328, 5733.655980641175, 5846.18153458514, 5870.442307980735, 5885.117232415129, 5888.793892213774, 5852.76588936442, 5871.853203669921, 5877.471027607515, 5880.911786323187, 5886.705664716768, 5762.106873434583, 5820.767169313222, 5873.1190321451695, 5878.823635290948, 5888.499477544436, 5294.735263236823, 5307.840764777063, 5319.148936170194, 5325.733713314357, 5327.866803329909, 24794.752052479424, 25339.239822805346, 25346.112886049083, 25382.619260711104, 25498.0, 2504.4615407687434, 2543.0209369392733, 2575.3046677397183, 2619.410632607667, 2809.2695899066184, 14786.18675972821, 15710.842891571052, 15738.449154279546, 15833.762493562606, 16221.188940553044, 1961.3233434465087, 1970.6354324450022, 2061.6186005589016, 2063.2572273981355, 2093.27781915239, 59217.91044776119, 64625.0, 70617.7629342236, 70836.64581677059, 76492.61753691251, 11278.661640150809, 11397.487113079931, 11462.541498339973, 11593.130515211393, 11999.820002699966, 15969.201539922957, 15971.281292341873, 15988.280527376064, 15990.0, 15990.840091599168, 8690.174433428814, 8826.646934122564, 8986.19124278818, 9018.954905225432, 9058.139476749699, 8949.0, 8953.776155596162, 9003.594838232295, 9018.954905225562, 9235.676751313671, 4773.793274599826, 4788.138135135709, 4811.013742182844, 5219.295395121686, 5275.57795376369, 2112.4402033461147, 2133.5519540896585, 2164.415607785889, 2177.1835561664375, 2201.3395981205576, 810.8566920641866, 910.8770316007386, 930.9022552632011, 1009.8333774927202, 1095.554500870115, 20727.896360518404, 21278.574428511343, 21359.25242616501, 21719.239826605994, 21778.0, 991.7718924647371, 1004.352192835617, 1010.8281592129356, 1020.0309705779539, 1037.3464717228098, 9775.804483910282, 11665.0, 11792.41037948099, 11853.76292474163, 11944.0, 558386.0, 558582.0, 565059.0, 571882.0, 573736.0, 91326.54336728273, 91561.54219228994, 94592.0, 94680.0, 100796.49601751944, 1046.7540128069897, 1074.0655629602452, 1096.2655021135859, 1096.4079397125463, 1098.3958822647512, 18666.533336666147, 19668.311609094184, 19697.310594129136, 19810.801891980896, 19843.206271748968, 44521.77739111284, 45155.54844451514, 45325.0, 45895.3115703258, 47961.280580792016, 63257.0, 63531.0, 64680.0, 65148.674256628416, 65711.34288657244, 7906.486078404885, 8111.269985701315, 8227.86075620331, 8388.496690198608, 8952.597133128895, 11048.337099774039, 12144.0, 14078.718425631634, 14495.057821241586, 14630.268486575416, 12432.191907525981, 12730.745385092432, 12951.532232454838, 12970.156939798882, 13162.276074815976, 12015.81976270374, 12694.551321831947, 12853.807192891927, 13100.541481048118, 13151.802722958972, 6706.429953453816, 12768.233905965672, 13050.804237936249, 13085.691430856838, 13260.138091024439, 9383.041704060257, 9573.712788616353, 9586.376885502421, 9730.0, 9857.852132218166, 11145.498452569816, 12902.67743306425, 13094.0, 13137.540186093442, 13589.1846489207], "value": 9852.817779567737, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.6647371104646, 1519.4529969211085, 1710.1278348042501, 1860.246600126949, 1860.516265770899, 9204.263658907288, 9906.554205060771, 9911.950440247789, 9917.553710083046, 9921.851172232404, 8255.793605159859, 9201.447913125201, 9465.100815422542, 9519.095685909844, 9519.095685909844, 2445.902163913441, 2491.9252422427357, 2496.3135137837057, 2499.6500489931423, 2502.862342571158, 2408.602580574212, 2563.6923569171727, 2565.8203925725206, 2565.8332208406396, 2568.666073410454, 2522.3946252899354, 2525.6716626838484, 2559.1298958354155, 2560.1039636127393, 2560.1679454177374, 2467.037855236459, 2484.503099380131, 2495.0643508684243, 2503.662005629235, 2518.7859031982302, 2418.4195793009726, 2467.2721547143638, 2468.8518688878726, 2471.7033955925226, 2486.651868738378, 9506.191973682246, 9557.569909354026, 9653.806923861483, 9784.0, 9788.0, 5637.182608521742, 5766.509846663039, 5810.099434587646, 5817.970910145465, 5818.854528636818, 3547.875824346161, 3643.890683279506, 3713.628637136265, 3722.236941427001, 3731.8880433587037, 1115.9621551956677, 1216.9087318451152, 1217.5312504685671, 1218.244688293255, 1247.0647014738945, 14138.646533836634, 14209.715805683933, 14217.644558886008, 14241.00312978092, 14259.429622815074, 4955.35580374549, 5050.84847454577, 5117.565006974374, 5147.871303217375, 5186.870328241749, 11741.706457338634, 11756.05951523876, 11775.293482390913, 11788.646340609796, 11789.174757766874, 5807.390224026501, 5815.621984570989, 5820.708964551755, 5852.824415267549, 5859.853503662443, 4732.455767586772, 4814.15752243356, 5265.6050796189675, 5344.385395679469, 5361.383440904346, 4364.367166760802, 4378.452693413324, 4422.71252368595, 4453.19842428366, 4465.620422264079, 1332.247280286635, 1342.8992825538123, 1354.8848347890344, 1370.766969615148, 1383.2184815579164, 995.5918073589938, 1026.7484466305652, 1048.365738728074, 1049.1134990932649, 1067.572970811666, 6490.247633139026, 6642.402183803384, 6706.161729783777, 6825.624590647462, 6828.112345395067, 483.65177072507737, 486.29972839111565, 490.99263511047366, 499.08168969097034, 511.8003978448408, 4580.129775342674, 4594.218982772936, 4643.3963584733765, 4678.976605116953, 4686.882827929261, 12280.815787763191, 12460.439280232411, 12593.685157871127, 12626.684332891751, 12637.810432843515, 11557.537698491966, 12557.309347985765, 12610.936945315398, 12616.0, 12656.987441004698, 8836.469811811312, 9056.954715226513, 9060.501672407949, 9067.81864362709, 9079.455232685928, 6691.0, 6749.0888730020815, 6829.52193346461, 6905.205901321211, 7048.541844780073, 11046.171537134587, 11406.600768973047, 11514.30914145154, 11522.53909843597, 11548.48031838586, 10935.343879367263, 11782.0, 11791.587294444997, 11845.46695398692, 11944.462499187732, 7821.921780782347, 8506.319494440553, 8794.956025220088, 8801.163889430494, 8960.551972401485, 9081.091890810865, 9479.194268487256, 9480.668176613786, 9486.667966621135, 9490.28822838276, 8206.22040906113, 8248.83502329962, 8260.710875119343, 8340.165983401612, 8403.83192336162, 8020.197980202167, 8037.557934313669, 8097.433179677366, 8243.093259741543, 8432.915670843213, 7135.250798666273, 7182.928170718435, 7468.4398670101045, 7586.089669239726, 7657.846843062996, 5865.384134665969, 5951.345352011362, 6015.308239552507, 6048.213732214784, 6050.0, 5851.8244452664785, 5919.526437884872, 5945.464908158287, 5994.580379373402, 6015.097735339734], "value": 5016.083071778849, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.32992304390785, 360.3819449643861, 362.8675533430299, 363.66542780641805, 365.23483302481293, 5447.1829225616175, 5473.4526547345295, 5485.478879506445, 5495.917561236574, 5586.55307575394, 5408.2698835657175, 5417.483104730672, 5436.75534600943, 5449.455054494549, 5482.23248745176, 4146.502419709639, 4260.70175087744, 4276.807543660527, 4298.548652391501, 4302.440682711243, 4276.631477927064, 4286.549912259215, 4288.292431748758, 4305.655547556204, 4330.740155590659, 4280.486341639008, 4282.507511636155, 4283.657307415415, 4287.363271587982, 4295.377170310303, 4173.749575025493, 4217.894552636178, 4252.085801552681, 4311.698181127323, 4317.8989357713735, 3760.830762615689, 3765.152840610868, 3767.397216445383, 3773.622637736232, 3790.8104594770152, 5307.416184219733, 5313.628046036779, 5342.893142137174, 5346.946530534723, 5360.892782144374, 4731.455882573514, 4826.879328016793, 4887.413510378742, 4900.558949694545, 4912.533309335608, 5563.805266815682, 5565.94247093051, 5583.804566840141, 5586.190002449622, 5588.133839254922, 4144.5026596808275, 4144.834206631731, 4152.958470415318, 4159.646430053449, 4179.226843034048, 5671.602987790856, 5831.679257640828, 5838.678872662044, 5841.85395365115, 5887.175795388649, 4248.426462427533, 4248.957510424918, 4257.87226383209, 4268.146370725867, 4308.159908817751, 4334.461266250499, 4343.3484977253665, 4355.8475453359615, 4360.171567402183, 4369.934450983238, 4653.930191047137, 4874.268859671007, 4911.582515486152, 4919.262110683355, 4924.089043526959, 5633.492985631313, 5649.858753531194, 5663.631863928831, 5675.943240567542, 5677.2619559457835, 1470.9264536773119, 1501.5495351394538, 1503.3460444706368, 1506.7212565675386, 1507.9698406031819, 930.02812061396, 964.6189755046718, 985.2659768472533, 985.975350616226, 987.1855719031828, 4967.428745694291, 4986.6010719143105, 4998.375203099613, 5013.54878060969, 5017.949820501748, 5191.442567229816, 5199.948000519947, 5205.323307970016, 5209.947900521022, 5235.534050465875, 4983.227599721977, 4987.875303117378, 4988.6507944444265, 5216.973915130476, 5578.633234857486, 3120.4695201815293, 3125.8280794556063, 3126.499760038383, 3128.311771410286, 3147.937041259208, 6069.332373438918, 6084.361142080018, 6110.511159107261, 6137.110119032715, 6139.877202455926, 5703.431556322044, 5735.2257445245195, 5973.49225315853, 6022.277326720734, 6025.487833534199, 5872.853178670567, 6047.758089676364, 6104.786332478431, 6121.84695382619, 6126.663033533109, 5855.619384739978, 5855.795047173328, 5871.647701137946, 5876.382979787061, 5876.500497457764, 5629.718514074362, 5823.475887170175, 5853.151293062568, 5865.853353666193, 5867.647941123546, 6022.909656355246, 6127.754889804358, 6136.23297087864, 6137.263528376623, 6155.507559395148, 4110.712250142461, 4315.978420107879, 4345.348197770233, 4353.934690979574, 4368.2355587772645, 5776.133579963039, 5821.534277257894, 5822.184894114743, 5829.15477255804, 5835.67903765297, 5658.490735833795, 5672.815965853094, 5680.375158732453, 5683.886322273449, 5709.800156994485, 5600.7101307692765, 5646.237757902631, 5721.370649228665, 5741.339745929272, 5752.741126649395, 5618.909599720219, 5653.830385088289, 5659.924614323266, 5673.404292549388, 5711.314642242957, 5572.3034620672415, 5686.77252909879, 5713.8285851424525, 5733.197352370755, 5751.33859606134, 5598.468145526169, 5626.381098079126, 5633.746481408429, 5640.435956404327, 5654.773809047592], "value": 4352.698739401893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.6643909216762, 846.8306338732256, 892.8437523433399, 895.6820328783282, 927.3137877970299, 16045.0, 16115.919420402895, 16676.916615416907, 17018.82981170188, 17039.0, 16378.181090945465, 17303.13484325785, 17329.220185091668, 17331.480055598353, 17348.306067757276, 733.9376153026999, 854.2610641794865, 855.3328403845011, 881.7310720230328, 886.8403687336277, 853.4793775796759, 857.8027053777635, 858.1932982995995, 858.5664239559014, 868.6004437958546, 905.6603773584906, 905.6875377994581, 906.1346414174448, 906.5150144672601, 906.768773962642, 904.886889138861, 905.1355955062899, 905.180811365715, 905.3662436294587, 906.3519583497798, 659.4394764450227, 659.4856012310405, 660.1253339325413, 660.1978596005875, 660.1978596005875, 15670.373185072698, 15724.0, 15726.05643661384, 15729.842701573067, 15752.921235393864, 16698.499045028668, 16754.916225418918, 16757.49727508177, 16839.74740378895, 17184.484465466056, 13035.608931732064, 13218.735625287536, 13277.0, 13474.865251347459, 13568.93215533916, 4143.875683729493, 4214.683898707609, 4226.809793559297, 4280.122574872144, 4329.631981281595, 60807.0, 85405.0, 91212.54393727989, 92965.07034929632, 93759.53120234492, 13024.47902083925, 13040.282784446757, 13074.411651475706, 13241.404136813866, 13330.533431329857, 22304.0, 22469.662955055686, 22569.66145507819, 22641.094356225567, 22652.320430387113, 8783.648654053894, 8874.156955089386, 9026.503542305236, 9231.856223069197, 9268.95365523168, 5051.444341122474, 5080.415752188472, 5089.312942752755, 5111.974440127776, 5336.226247194135, 3891.902702432405, 3987.2623564640635, 3998.0804414984136, 4008.198360327946, 4334.371516130143, 769.6305773228881, 771.4484143837184, 779.0884664942031, 779.1195948578184, 791.5290402210676, 746.268656716421, 774.9457537972401, 782.9686812527549, 785.5169071021281, 788.6805843633338, 9252.908878273145, 9991.500424978722, 10424.843627345595, 10441.947790261152, 10516.0, 702.5047341624143, 731.608589404666, 761.2501685839444, 772.7140957845633, 831.9126491718404, 4802.951970480251, 5085.771140298695, 5130.384353877484, 5186.896262074737, 5187.0922588546855, 74123.0, 74125.62937185279, 74635.0, 74849.0, 112160.0, 102999.9999999985, 104791.0, 104973.0, 107842.0, 107980.0, 88492.5575372119, 94504.52747736355, 94712.10575788446, 95119.0, 95220.04779952111, 9379.437233765995, 9619.90380096218, 9744.805103897741, 9796.0, 9827.557759900965, 36613.450798237514, 38510.0, 43443.782781085894, 45351.77324113358, 54252.016139112726, 74723.0, 77613.0, 80355.0, 81444.59277703811, 81588.59205703934, 12953.0, 13276.601701948957, 13471.271433033051, 13558.932205339304, 13693.589192324247, 13881.444742210602, 14015.649608759863, 14474.782878256625, 14753.114813111248, 14988.925055374653, 12534.122611417113, 12667.0, 13612.387442565307, 13919.512817051354, 14735.92632036833, 13505.864941350854, 13713.588592342247, 13839.169649821042, 13895.930520347334, 14210.644733881736, 13731.794023089862, 13754.449822007007, 13858.861411385757, 13862.792058119338, 14244.646758557921, 11493.310401375944, 11848.822267665819, 12199.817002745143, 12447.004239660986, 12706.237625742486, 11549.36478493691, 11701.88298117008, 11754.033757156802, 11806.350650714294, 11991.520339186334], "value": 7995.24586448523, "unit": "score"}], "name": "dromaeo_css", "value": 7706.736462492829, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 121.0, 111.0, 110.0, 111.0, 109.0, 112.0, 112.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 143.0, 182.0, 119.0, 146.0, 149.0, 121.0, 170.0, 115.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 162.0, 161.0, 160.0, 161.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 75.0, 175.0, 76.0, 76.0, 76.0, 75.0, 174.0, 76.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 158.0, 84.0, 83.0, 84.0, 148.0, 83.0, 82.0, 144.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 52.0, 51.0, 52.0, 50.0, 51.0, 50.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 61.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 74.0, 73.0, 80.0, 72.0, 73.0, 76.0, 75.0, 72.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 129.0, 124.0, 120.0, 118.0, 128.0, 120.0, 120.0, 151.0, 125.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 151.0, 153.0, 151.0, 152.0, 152.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 64.0, 62.0, 63.0, 60.0, 59.0, 61.0, 59.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1491.6, "alertThreshold": 2.0}]} 18:43:15 INFO - Return code: 0 18:43:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:43:16 INFO - # TBPL SUCCESS # 18:43:16 INFO - Running post-action listener: _resource_record_post_action 18:43:16 INFO - [mozharness: 2016-10-21 01:43:16.009541Z] Finished run-tests step (success) 18:43:16 INFO - Running post-run listener: _resource_record_post_run 18:43:16 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 7761920; Write bytes: 417157120; Read time: 2276; Write time: 662960 18:43:16 INFO - TinderboxPrint: CPU usage
12.9% 18:43:16 INFO - TinderboxPrint: I/O read bytes / time
7,761,920 / 2,276 18:43:16 INFO - TinderboxPrint: I/O write bytes / time
417,157,120 / 662,960 18:43:16 INFO - TinderboxPrint: CPU idle
8,268.6 (87.0%) 18:43:16 INFO - TinderboxPrint: CPU user
1,178.8 (12.4%) 18:43:16 INFO - TinderboxPrint: Swap in / out
0 / 0 18:43:16 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71335936; Read time: 0; Write time: 172032 18:43:16 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 7761920; Write bytes: 345542656; Read time: 2276; Write time: 490896 18:43:16 INFO - Running post-run listener: _upload_blobber_files 18:43:16 INFO - Blob upload gear active. 18:43:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:43:16 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 18:43:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:43:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:43:17 INFO - (blobuploader) - INFO - Open directory for files ... 18:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:17 INFO - (blobuploader) - INFO - Done attempting. 18:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:43:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:18 INFO - (blobuploader) - INFO - Done attempting. 18:43:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:43:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:21 INFO - (blobuploader) - INFO - Done attempting. 18:43:21 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:21 INFO - Return code: 0 18:43:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74804a884992fec37c45e53e2ab55d23d957e726f7ffa024737b744b13b3f8e59d07b39118ad8249e07885921e87d08ab168b81d879ab747075fa1ac384a103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bc30d0aaceb9766cc258a66f50d128447314c8c40f65c915733bc49b91e6195c5a25d147ef980c95d33d34d509111baf3a4d690f0399d804f877a1c24b0a3ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a86c74ef611d5d8c193a94dbbfaaa8d146eeeccd015197c754be3cbedd3d28e02195b40bf95ac76462f318d555938dde7709b507335362937dacf9c12254422a"} 18:43:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:21 INFO - Contents: 18:43:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74804a884992fec37c45e53e2ab55d23d957e726f7ffa024737b744b13b3f8e59d07b39118ad8249e07885921e87d08ab168b81d879ab747075fa1ac384a103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bc30d0aaceb9766cc258a66f50d128447314c8c40f65c915733bc49b91e6195c5a25d147ef980c95d33d34d509111baf3a4d690f0399d804f877a1c24b0a3ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a86c74ef611d5d8c193a94dbbfaaa8d146eeeccd015197c754be3cbedd3d28e02195b40bf95ac76462f318d555938dde7709b507335362937dacf9c12254422a"} 18:43:21 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:21 INFO - Copying logs to upload dir... 18:43:21 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.326491 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-10-20 18:43:21.557526) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 18:43:21.561196) ========= 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=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74804a884992fec37c45e53e2ab55d23d957e726f7ffa024737b744b13b3f8e59d07b39118ad8249e07885921e87d08ab168b81d879ab747075fa1ac384a103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bc30d0aaceb9766cc258a66f50d128447314c8c40f65c915733bc49b91e6195c5a25d147ef980c95d33d34d509111baf3a4d690f0399d804f877a1c24b0a3ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a86c74ef611d5d8c193a94dbbfaaa8d146eeeccd015197c754be3cbedd3d28e02195b40bf95ac76462f318d555938dde7709b507335362937dacf9c12254422a"} build_url:https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/Ifr8hOM7TRSO9oauf-mkRw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74804a884992fec37c45e53e2ab55d23d957e726f7ffa024737b744b13b3f8e59d07b39118ad8249e07885921e87d08ab168b81d879ab747075fa1ac384a103", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bc30d0aaceb9766cc258a66f50d128447314c8c40f65c915733bc49b91e6195c5a25d147ef980c95d33d34d509111baf3a4d690f0399d804f877a1c24b0a3ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a86c74ef611d5d8c193a94dbbfaaa8d146eeeccd015197c754be3cbedd3d28e02195b40bf95ac76462f318d555938dde7709b507335362937dacf9c12254422a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 18:43:21.588200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:43:21.588690) ========= 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=5cf47e208febbcd81da23b2e0000026f-1477012520.828256-1123537594 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004405 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:43:21.671333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 18:43:21.671648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 18:43:21.671923) ========= ========= Total master_lag: 0.20 =========