builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1477601545.15 results: success (0) buildid: 20161027110534 builduid: 58109e52ec42484c8b4ac57bdfcbc1d6 revision: 829a3f99f2606759305e3db204185242566a4ca6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.153817) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.154345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.154620) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.169679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.170005) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-27 13:52:26-- 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% 62.3M=0s 2016-10-27 13:52:26 (62.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.295076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.295421) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015211 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:25.345150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 13:52:25.345505) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 829a3f99f2606759305e3db204185242566a4ca6 --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 829a3f99f2606759305e3db204185242566a4ca6 --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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-27 13:52:26,368 truncating revision to first 12 chars 2016-10-27 13:52:26,368 Setting DEBUG logging. 2016-10-27 13:52:26,368 attempt 1/10 2016-10-27 13:52:26,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/829a3f99f260?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 13:52:27,161 unpacking tar archive at: mozilla-beta-829a3f99f260/testing/mozharness/ program finished with exit code 0 elapsedTime=1.170132 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 13:52:26.529042) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:26.529375) ========= script_repo_revision: 829a3f99f2606759305e3db204185242566a4ca6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:26.529752) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:26.530009) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 13:52:26.541022) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-27 13:52:26.541407) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:52:27 INFO - MultiFileLogger online at 20161027 13:52:27 in /builds/slave/test 13:52:27 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 13:52:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:52:27 INFO - {'append_to_log': False, 13:52:27 INFO - 'base_work_dir': '/builds/slave/test', 13:52:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:52:27 INFO - 'branch': 'Mozilla-Beta', 13:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 13:52:27 INFO - 'config_files': ('talos/linux_config.py',), 13:52:27 INFO - 'default_actions': ('clobber', 13:52:27 INFO - 'read-buildbot-config', 13:52:27 INFO - 'download-and-extract', 13:52:27 INFO - 'populate-webroot', 13:52:27 INFO - 'create-virtualenv', 13:52:27 INFO - 'install', 13:52:27 INFO - 'run-tests'), 13:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:52:27 INFO - 'download_minidump_stackwalk': True, 13:52:27 INFO - 'download_symbols': 'ondemand', 13:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 13:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 13:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:52:27 INFO - 'installer_path': 'installer.exe', 13:52:27 INFO - 'log_level': 'info', 13:52:27 INFO - 'log_name': 'talos', 13:52:27 INFO - 'log_to_console': True, 13:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:52:27 INFO - 'opt_config_files': (), 13:52:27 INFO - 'pip_index': False, 13:52:27 INFO - 'sps_profile': False, 13:52:27 INFO - 'sps_profile_interval': 0, 13:52:27 INFO - 'suite': 'dromaeojs', 13:52:27 INFO - 'system_bits': '32', 13:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:52:27 INFO - 'title': 'talos-linux64-ix-034', 13:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:52:27 INFO - 'use_talos_json': True, 13:52:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:52:27 INFO - 'work_dir': 'build'} 13:52:27 INFO - [mozharness: 2016-10-27 20:52:27.656076Z] Running clobber step. 13:52:27 INFO - Running pre-action listener: _resource_record_pre_action 13:52:27 INFO - Running main action method: clobber 13:52:27 INFO - rmtree: /builds/slave/test/build 13:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:52:28 INFO - Running post-action listener: _resource_record_post_action 13:52:28 INFO - [mozharness: 2016-10-27 20:52:28.634220Z] Finished clobber step (success) 13:52:28 INFO - [mozharness: 2016-10-27 20:52:28.634320Z] Running read-buildbot-config step. 13:52:28 INFO - Running pre-action listener: _resource_record_pre_action 13:52:28 INFO - Running main action method: read_buildbot_config 13:52:28 INFO - Using buildbot properties: 13:52:28 INFO - { 13:52:28 INFO - "project": "", 13:52:28 INFO - "product": "firefox", 13:52:28 INFO - "script_repo_revision": "production", 13:52:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:52:28 INFO - "repository": "", 13:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:52:28 INFO - "buildid": "20161027110534", 13:52:28 INFO - "pgo_build": "True", 13:52:28 INFO - "basedir": "/builds/slave/test", 13:52:28 INFO - "buildnumber": 450, 13:52:28 INFO - "slavename": "talos-linux64-ix-034", 13:52:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:52:28 INFO - "platform": "ubuntu64_hw", 13:52:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:52:28 INFO - "branch": "mozilla-beta", 13:52:28 INFO - "repo_path": "releases/mozilla-beta", 13:52:28 INFO - "revision": "829a3f99f2606759305e3db204185242566a4ca6", 13:52:28 INFO - "stage_platform": "linux64-pgo", 13:52:28 INFO - "builduid": "58109e52ec42484c8b4ac57bdfcbc1d6", 13:52:28 INFO - "slavebuilddir": "test" 13:52:28 INFO - } 13:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 13:52:28 INFO - Running post-action listener: _resource_record_post_action 13:52:28 INFO - [mozharness: 2016-10-27 20:52:28.636786Z] Finished read-buildbot-config step (success) 13:52:28 INFO - [mozharness: 2016-10-27 20:52:28.636875Z] Running download-and-extract step. 13:52:28 INFO - Running pre-action listener: _resource_record_pre_action 13:52:28 INFO - Running main action method: download_and_extract 13:52:28 INFO - mkdir: /builds/slave/test/build/tests 13:52:28 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')]} 13:52:28 INFO - https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 13:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 13:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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! 13:53:58 INFO - Caught exception: HTTP Error 404: Not Found 13:53:58 INFO - Caught exception: HTTP Error 404: Not Found 13:53:58 INFO - Caught exception: HTTP Error 404: Not Found 13:53:58 INFO - trying https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:53:59 INFO - Downloaded 1327 bytes. 13:53:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:53:59 INFO - Using the following test package requirements: 13:53:59 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 13:53:59 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:53:59 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 13:53:59 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'jsshell-linux-x86_64.zip'], 13:53:59 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:53:59 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 13:53:59 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 13:53:59 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 13:53:59 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:53:59 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:53:59 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:53:59 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:53: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')]} 13:53:59 INFO - https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:53:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:53:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:53:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:53:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:53:59 INFO - retry: Failed, sleeping 30 seconds before retrying 13:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:54:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:54:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:54:29 INFO - retry: Failed, sleeping 60 seconds before retrying 13:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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! 13:55:29 INFO - Caught exception: HTTP Error 404: Not Found 13:55:29 INFO - Caught exception: HTTP Error 404: Not Found 13:55:29 INFO - Caught exception: HTTP Error 404: Not Found 13:55:29 INFO - trying https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:55:30 INFO - Downloaded 21789075 bytes. 13:55:30 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'] 13:55:30 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 13:55:31 INFO - Return code: 0 13:55:31 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 13:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:55:31 INFO - https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:55:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:55:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:55:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:55:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:55:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:55:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:55:31 INFO - retry: Failed, sleeping 30 seconds before retrying 13:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:56:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:56:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:56:01 INFO - retry: Failed, sleeping 60 seconds before retrying 13:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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! 13:57:01 INFO - Caught exception: HTTP Error 404: Not Found 13:57:01 INFO - Caught exception: HTTP Error 404: Not Found 13:57:01 INFO - Caught exception: HTTP Error 404: Not Found 13:57:01 INFO - trying https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:02 INFO - Downloaded 21789075 bytes. 13:57:02 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'] 13:57:02 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 13:57:03 INFO - Return code: 0 13:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:57:03 INFO - https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:57:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:57:03 INFO - retry: Failed, sleeping 30 seconds before retrying 13:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:57:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:57:33 INFO - retry: Failed, sleeping 60 seconds before retrying 13:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:58:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:58:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:58:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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! 13:58:33 INFO - Caught exception: HTTP Error 404: Not Found 13:58:33 INFO - Caught exception: HTTP Error 404: Not Found 13:58:33 INFO - Caught exception: HTTP Error 404: Not Found 13:58:33 INFO - trying https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:58:34 INFO - Downloaded 14413762 bytes. 13:58:34 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'] 13:58:34 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 13:58:34 INFO - Return code: 0 13:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:58:34 INFO - https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:58:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:58:34 INFO - retry: Failed, sleeping 30 seconds before retrying 13:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 13:59:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:59:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:59:04 INFO - retry: Failed, sleeping 60 seconds before retrying 14:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 14:00:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:00:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:00:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyUhgzcFQOORyptSJ-OXQg/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! 14:00:04 INFO - Caught exception: HTTP Error 404: Not Found 14:00:04 INFO - Caught exception: HTTP Error 404: Not Found 14:00:04 INFO - Caught exception: HTTP Error 404: Not Found 14:00:04 INFO - trying https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:00:04 INFO - Downloading https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 14:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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 14:00:06 INFO - Downloaded 56813882 bytes. 14:00:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:00:06 INFO - mkdir: /builds/slave/test/properties 14:00:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:00:06 INFO - Writing to file /builds/slave/test/properties/build_url 14:00:06 INFO - Contents: 14:00:06 INFO - build_url:https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:00:06 INFO - Running post-action listener: _resource_record_post_action 14:00:06 INFO - Running post-action listener: set_extra_try_arguments 14:00:06 INFO - [mozharness: 2016-10-27 21:00:06.722680Z] Finished download-and-extract step (success) 14:00:06 INFO - [mozharness: 2016-10-27 21:00:06.722952Z] Running populate-webroot step. 14:00:06 INFO - Running pre-action listener: _resource_record_pre_action 14:00:06 INFO - Running main action method: populate_webroot 14:00:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:00:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:00:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:00:06 INFO - '--tppagecycles', 14:00:06 INFO - '7'], 14:00:06 INFO - 'tests': ['tsvgm']}}, 14:00:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:00:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:00:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:00:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:00:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['damp', 'tps']}, 14:00:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'tests': ['damp', 'tps']}, 14:00:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['dromaeo_dom']}, 14:00:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:00:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['basic_compositor_video']}, 14:00:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:00:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'other-e10s': {'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['a11yr', 14:00:06 INFO - 'ts_paint', 14:00:06 INFO - 'tpaint', 14:00:06 INFO - 'sessionrestore', 14:00:06 INFO - 'sessionrestore_no_auto_restore', 14:00:06 INFO - 'tabpaint']}, 14:00:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['tsvgx', 14:00:06 INFO - 'tsvgr_opacity', 14:00:06 INFO - 'tart', 14:00:06 INFO - 'tscrollx', 14:00:06 INFO - 'cart']}, 14:00:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:00:06 INFO - 'tsvgr_opacity', 14:00:06 INFO - 'tart', 14:00:06 INFO - 'tscrollx', 14:00:06 INFO - 'cart']}, 14:00:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'talos_options': ['--disable-e10s'], 14:00:06 INFO - 'tests': ['tp5o']}, 14:00:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'tests': ['tp5o']}, 14:00:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'talos_options': ['--disable-e10s', 14:00:06 INFO - '--xperf_path', 14:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:06 INFO - 'tests': ['tp5n']}, 14:00:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:06 INFO - 'talos_options': ['--xperf_path', 14:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:06 INFO - 'tests': ['tp5n']}}, 14:00:06 INFO - 'talos.zip': {'path': '', 14:00:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:00:06 INFO - Running post-action listener: _resource_record_post_action 14:00:06 INFO - [mozharness: 2016-10-27 21:00:06.738825Z] Finished populate-webroot step (success) 14:00:06 INFO - [mozharness: 2016-10-27 21:00:06.738918Z] Running create-virtualenv step. 14:00:06 INFO - Running pre-action listener: _resource_record_pre_action 14:00:06 INFO - Running main action method: create_virtualenv 14:00:06 INFO - Creating virtualenv /builds/slave/test/build/venv 14:00:06 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 14:00:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:00:06 INFO - Using real prefix '/usr' 14:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 14:00:09 INFO - Installing pip.................done. 14:00:09 INFO - Return code: 0 14:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00: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', 'pip>=1.5'] in /builds/slave/test/build 14:00: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 pip>=1.5 14:00:09 INFO - Using env: {'DISPLAY': ':0', 14:00:09 INFO - 'HOME': '/home/cltbld', 14:00:09 INFO - 'LANG': 'en_US.UTF-8', 14:00:09 INFO - 'LANGUAGE': 'en_US:en', 14:00:09 INFO - 'LOGNAME': 'cltbld', 14:00:09 INFO - 'MAIL': '/var/mail/cltbld', 14:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:09 INFO - 'MOZ_NO_REMOTE': '1', 14:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:09 INFO - 'NO_EM_RESTART': '1', 14:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:09 INFO - 'PWD': '/builds/slave/test', 14:00:09 INFO - 'SHELL': '/bin/bash', 14:00:09 INFO - 'SHLVL': '1', 14:00:09 INFO - 'TERM': 'linux', 14:00:09 INFO - 'TMOUT': '86400', 14:00:09 INFO - 'USER': 'cltbld', 14:00:09 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:09 INFO - '_': '/tools/buildbot/bin/python'} 14:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:09 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 14:00:09 INFO - Cleaning up... 14:00:09 INFO - Return code: 0 14:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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', '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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:00: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 psutil>=3.1.1 14:00:09 INFO - Using env: {'DISPLAY': ':0', 14:00:09 INFO - 'HOME': '/home/cltbld', 14:00:09 INFO - 'LANG': 'en_US.UTF-8', 14:00:09 INFO - 'LANGUAGE': 'en_US:en', 14:00:09 INFO - 'LOGNAME': 'cltbld', 14:00:09 INFO - 'MAIL': '/var/mail/cltbld', 14:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:09 INFO - 'MOZ_NO_REMOTE': '1', 14:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:09 INFO - 'NO_EM_RESTART': '1', 14:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:09 INFO - 'PWD': '/builds/slave/test', 14:00:09 INFO - 'SHELL': '/bin/bash', 14:00:09 INFO - 'SHLVL': '1', 14:00:09 INFO - 'TERM': 'linux', 14:00:09 INFO - 'TMOUT': '86400', 14:00:09 INFO - 'USER': 'cltbld', 14:00:09 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:09 INFO - '_': '/tools/buildbot/bin/python'} 14:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:09 INFO - Downloading/unpacking psutil>=3.1.1 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:11 INFO - Installing collected packages: psutil 14:00:11 INFO - Running setup.py install for psutil 14:00:11 INFO - building 'psutil._psutil_linux' extension 14:00:11 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 14:00:12 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 14:00:12 INFO - building 'psutil._psutil_posix' extension 14:00:12 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 14:00:12 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 14:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:12 INFO - Successfully installed psutil 14:00:12 INFO - Cleaning up... 14:00:12 INFO - Return code: 0 14:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:00:12 INFO - Using env: {'DISPLAY': ':0', 14:00:12 INFO - 'HOME': '/home/cltbld', 14:00:12 INFO - 'LANG': 'en_US.UTF-8', 14:00:12 INFO - 'LANGUAGE': 'en_US:en', 14:00:12 INFO - 'LOGNAME': 'cltbld', 14:00:12 INFO - 'MAIL': '/var/mail/cltbld', 14:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:12 INFO - 'MOZ_NO_REMOTE': '1', 14:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:12 INFO - 'NO_EM_RESTART': '1', 14:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:12 INFO - 'PWD': '/builds/slave/test', 14:00:12 INFO - 'SHELL': '/bin/bash', 14:00:12 INFO - 'SHLVL': '1', 14:00:12 INFO - 'TERM': 'linux', 14:00:12 INFO - 'TMOUT': '86400', 14:00:12 INFO - 'USER': 'cltbld', 14:00:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:12 INFO - '_': '/tools/buildbot/bin/python'} 14:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:00:14 INFO - Installing collected packages: mozsystemmonitor 14:00:14 INFO - Running setup.py install for mozsystemmonitor 14:00:14 INFO - Successfully installed mozsystemmonitor 14:00:14 INFO - Cleaning up... 14:00:14 INFO - Return code: 0 14:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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', '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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:00: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 jsonschema==2.5.1 14:00:14 INFO - Using env: {'DISPLAY': ':0', 14:00:14 INFO - 'HOME': '/home/cltbld', 14:00:14 INFO - 'LANG': 'en_US.UTF-8', 14:00:14 INFO - 'LANGUAGE': 'en_US:en', 14:00:14 INFO - 'LOGNAME': 'cltbld', 14:00:14 INFO - 'MAIL': '/var/mail/cltbld', 14:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:14 INFO - 'MOZ_NO_REMOTE': '1', 14:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:14 INFO - 'NO_EM_RESTART': '1', 14:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:14 INFO - 'PWD': '/builds/slave/test', 14:00:14 INFO - 'SHELL': '/bin/bash', 14:00:14 INFO - 'SHLVL': '1', 14:00:14 INFO - 'TERM': 'linux', 14:00:14 INFO - 'TMOUT': '86400', 14:00:14 INFO - 'USER': 'cltbld', 14:00:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:14 INFO - '_': '/tools/buildbot/bin/python'} 14:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 14:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:00:17 INFO - warning: no files found matching '*.txt' 14:00:17 INFO - no previously-included directories found matching 'build' 14:00:17 INFO - no previously-included directories found matching 'dist' 14:00:17 INFO - no previously-included directories found matching '.git*' 14:00:17 INFO - Installing collected packages: jsonschema, functools32 14:00:17 INFO - Running setup.py install for functools32 14:00:17 INFO - warning: no files found matching '*.txt' 14:00:17 INFO - no previously-included directories found matching 'build' 14:00:17 INFO - no previously-included directories found matching 'dist' 14:00:17 INFO - no previously-included directories found matching '.git*' 14:00:17 INFO - Successfully installed jsonschema functools32 14:00:17 INFO - Cleaning up... 14:00:17 INFO - Return code: 0 14:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:17 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:17 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 14:00:17 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 14:00:17 INFO - Using env: {'DISPLAY': ':0', 14:00:17 INFO - 'HOME': '/home/cltbld', 14:00:17 INFO - 'LANG': 'en_US.UTF-8', 14:00:17 INFO - 'LANGUAGE': 'en_US:en', 14:00:17 INFO - 'LOGNAME': 'cltbld', 14:00:17 INFO - 'MAIL': '/var/mail/cltbld', 14:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:17 INFO - 'MOZ_NO_REMOTE': '1', 14:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:17 INFO - 'NO_EM_RESTART': '1', 14:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:17 INFO - 'PWD': '/builds/slave/test', 14:00:17 INFO - 'SHELL': '/bin/bash', 14:00:17 INFO - 'SHLVL': '1', 14:00:17 INFO - 'TERM': 'linux', 14:00:17 INFO - 'TMOUT': '86400', 14:00:17 INFO - 'USER': 'cltbld', 14:00:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:17 INFO - '_': '/tools/buildbot/bin/python'} 14:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:00:17 INFO - Cleaning up... 14:00:17 INFO - Return code: 0 14:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:17 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:17 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 14:00:17 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 14:00:17 INFO - Using env: {'DISPLAY': ':0', 14:00:17 INFO - 'HOME': '/home/cltbld', 14:00:17 INFO - 'LANG': 'en_US.UTF-8', 14:00:17 INFO - 'LANGUAGE': 'en_US:en', 14:00:17 INFO - 'LOGNAME': 'cltbld', 14:00:17 INFO - 'MAIL': '/var/mail/cltbld', 14:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:17 INFO - 'MOZ_NO_REMOTE': '1', 14:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:17 INFO - 'NO_EM_RESTART': '1', 14:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:17 INFO - 'PWD': '/builds/slave/test', 14:00:17 INFO - 'SHELL': '/bin/bash', 14:00:17 INFO - 'SHLVL': '1', 14:00:17 INFO - 'TERM': 'linux', 14:00:17 INFO - 'TMOUT': '86400', 14:00:17 INFO - 'USER': 'cltbld', 14:00:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:17 INFO - '_': '/tools/buildbot/bin/python'} 14:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 14:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:00:19 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 14:00:19 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 14:00:19 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 14:00:19 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 14:00:19 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 14:00:19 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 14:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:20 INFO - Downloading docopt-0.6.1.tar.gz 14:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 14:00:20 INFO - Running setup.py install for blobuploader 14:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:00:20 INFO - Running setup.py install for requests 14:00:20 INFO - Running setup.py install for docopt 14:00:20 INFO - Successfully installed blobuploader requests docopt 14:00:20 INFO - Cleaning up... 14:00:21 INFO - Return code: 0 14:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:21 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:21 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 14:00:21 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 14:00:21 INFO - Using env: {'DISPLAY': ':0', 14:00:21 INFO - 'HOME': '/home/cltbld', 14:00:21 INFO - 'LANG': 'en_US.UTF-8', 14:00:21 INFO - 'LANGUAGE': 'en_US:en', 14:00:21 INFO - 'LOGNAME': 'cltbld', 14:00:21 INFO - 'MAIL': '/var/mail/cltbld', 14:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:21 INFO - 'MOZ_NO_REMOTE': '1', 14:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:21 INFO - 'NO_EM_RESTART': '1', 14:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:21 INFO - 'PWD': '/builds/slave/test', 14:00:21 INFO - 'SHELL': '/bin/bash', 14:00:21 INFO - 'SHLVL': '1', 14:00:21 INFO - 'TERM': 'linux', 14:00:21 INFO - 'TMOUT': '86400', 14:00:21 INFO - 'USER': 'cltbld', 14:00:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:21 INFO - '_': '/tools/buildbot/bin/python'} 14:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:21 INFO - Running setup.py (path:/tmp/pip-LyA7tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:21 INFO - Running setup.py (path:/tmp/pip-efHMtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:21 INFO - Running setup.py (path:/tmp/pip-0B1SoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:21 INFO - Running setup.py (path:/tmp/pip-8epa2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:21 INFO - Running setup.py (path:/tmp/pip-qQDYxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:21 INFO - Running setup.py (path:/tmp/pip-lZywex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:21 INFO - Running setup.py (path:/tmp/pip-XmbQyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:21 INFO - Running setup.py (path:/tmp/pip-d8laJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:21 INFO - Running setup.py (path:/tmp/pip-QOlzP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:21 INFO - Running setup.py (path:/tmp/pip-GHL4Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:21 INFO - Running setup.py (path:/tmp/pip-LLqJc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:22 INFO - Running setup.py (path:/tmp/pip-os9f1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:22 INFO - Running setup.py (path:/tmp/pip-LsDKgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:22 INFO - Running setup.py (path:/tmp/pip-c2LRyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:22 INFO - Running setup.py (path:/tmp/pip-eh85sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:22 INFO - Running setup.py (path:/tmp/pip-9hMlpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:22 INFO - Running setup.py (path:/tmp/pip-bQRr4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:00:22 INFO - Running setup.py install for manifestparser 14:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:00:22 INFO - Running setup.py install for mozcrash 14:00:22 INFO - Running setup.py install for mozdebug 14:00:22 INFO - Running setup.py install for mozdevice 14:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:00:22 INFO - Running setup.py install for mozfile 14:00:23 INFO - Running setup.py install for mozhttpd 14:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozinfo 14:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozInstall 14:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozleak 14:00:23 INFO - Running setup.py install for mozlog 14:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for moznetwork 14:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozprocess 14:00:23 INFO - Running setup.py install for mozprofile 14:00:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozrunner 14:00:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:00:23 INFO - Running setup.py install for mozscreenshot 14:00:24 INFO - Running setup.py install for moztest 14:00:24 INFO - Running setup.py install for mozversion 14:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:00:24 INFO - Cleaning up... 14:00:24 INFO - Return code: 0 14:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:24 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:24 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 14:00:24 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 14:00:24 INFO - Using env: {'DISPLAY': ':0', 14:00:24 INFO - 'HOME': '/home/cltbld', 14:00:24 INFO - 'LANG': 'en_US.UTF-8', 14:00:24 INFO - 'LANGUAGE': 'en_US:en', 14:00:24 INFO - 'LOGNAME': 'cltbld', 14:00:24 INFO - 'MAIL': '/var/mail/cltbld', 14:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:24 INFO - 'MOZ_NO_REMOTE': '1', 14:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:24 INFO - 'NO_EM_RESTART': '1', 14:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:24 INFO - 'PWD': '/builds/slave/test', 14:00:24 INFO - 'SHELL': '/bin/bash', 14:00:24 INFO - 'SHLVL': '1', 14:00:24 INFO - 'TERM': 'linux', 14:00:24 INFO - 'TMOUT': '86400', 14:00:24 INFO - 'USER': 'cltbld', 14:00:24 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:24 INFO - '_': '/tools/buildbot/bin/python'} 14:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:24 INFO - Running setup.py (path:/tmp/pip-cheb2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:24 INFO - Running setup.py (path:/tmp/pip-yCWCl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:24 INFO - Running setup.py (path:/tmp/pip-pp4Zk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:24 INFO - Running setup.py (path:/tmp/pip-HlASOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:24 INFO - Running setup.py (path:/tmp/pip-qH3oDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:24 INFO - Running setup.py (path:/tmp/pip-TleQw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:24 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)) 14:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:24 INFO - Running setup.py (path:/tmp/pip-OgyY5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:25 INFO - Running setup.py (path:/tmp/pip-jyFZVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:25 INFO - Running setup.py (path:/tmp/pip-7jD1Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:25 INFO - Running setup.py (path:/tmp/pip-gNMa07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:25 INFO - Running setup.py (path:/tmp/pip-U1m8G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:25 INFO - Running setup.py (path:/tmp/pip-2fNB8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:25 INFO - Running setup.py (path:/tmp/pip-mQnmtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:25 INFO - Running setup.py (path:/tmp/pip-8nqxSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:25 INFO - Running setup.py (path:/tmp/pip-uRd9Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:25 INFO - Running setup.py (path:/tmp/pip-NizVud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:25 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)) 14:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:25 INFO - Running setup.py (path:/tmp/pip-RcwVhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:25 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)) 14:00:25 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)) 14:00:25 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)) 14:00:25 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)) 14:00:25 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)) 14:00:25 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)) 14:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:25 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 14:00:25 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 14:00:25 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 14:00:25 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 14:00:25 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 14:00:25 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 14:00:27 INFO - Downloading blessings-1.6.tar.gz 14:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:00:27 INFO - Installing collected packages: blessings 14:00:27 INFO - Running setup.py install for blessings 14:00:27 INFO - Successfully installed blessings 14:00:27 INFO - Cleaning up... 14:00:27 INFO - Return code: 0 14:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:28 INFO - Reading from file tmpfile_stdout 14:00:28 INFO - Current package versions: 14:00:28 INFO - argparse == 1.2.1 14:00:28 INFO - blessings == 1.6 14:00:28 INFO - blobuploader == 1.2.4 14:00:28 INFO - docopt == 0.6.1 14:00:28 INFO - functools32 == 3.2.3-2 14:00:28 INFO - jsonschema == 2.5.1 14:00:28 INFO - manifestparser == 1.1 14:00:28 INFO - mozInstall == 1.12 14:00:28 INFO - mozcrash == 0.17 14:00:28 INFO - mozdebug == 0.1 14:00:28 INFO - mozdevice == 0.48 14:00:28 INFO - mozfile == 1.2 14:00:28 INFO - mozhttpd == 0.7 14:00:28 INFO - mozinfo == 0.9 14:00:28 INFO - mozleak == 0.1 14:00:28 INFO - mozlog == 3.3 14:00:28 INFO - moznetwork == 0.27 14:00:28 INFO - mozprocess == 0.23 14:00:28 INFO - mozprofile == 0.28 14:00:28 INFO - mozrunner == 6.12 14:00:28 INFO - mozscreenshot == 0.1 14:00:28 INFO - mozsystemmonitor == 0.3 14:00:28 INFO - moztest == 0.7 14:00:28 INFO - mozversion == 1.4 14:00:28 INFO - psutil == 3.1.1 14:00:28 INFO - requests == 1.2.3 14:00:28 INFO - wsgiref == 0.1.2 14:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:28 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')]} 14:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:28 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')]} 14:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:28 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:28 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 14:00:28 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 14:00:28 INFO - Using env: {'DISPLAY': ':0', 14:00:28 INFO - 'HOME': '/home/cltbld', 14:00:28 INFO - 'LANG': 'en_US.UTF-8', 14:00:28 INFO - 'LANGUAGE': 'en_US:en', 14:00:28 INFO - 'LOGNAME': 'cltbld', 14:00:28 INFO - 'MAIL': '/var/mail/cltbld', 14:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:28 INFO - 'MOZ_NO_REMOTE': '1', 14:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:28 INFO - 'NO_EM_RESTART': '1', 14:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:28 INFO - 'PWD': '/builds/slave/test', 14:00:28 INFO - 'SHELL': '/bin/bash', 14:00:28 INFO - 'SHLVL': '1', 14:00:28 INFO - 'TERM': 'linux', 14:00:28 INFO - 'TMOUT': '86400', 14:00:28 INFO - 'USER': 'cltbld', 14:00:28 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:28 INFO - '_': '/tools/buildbot/bin/python'} 14:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 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)) 14:00:28 INFO - Cleaning up... 14:00:28 INFO - Return code: 0 14:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:00:28 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')]} 14:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:28 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')]} 14:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:28 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 0xb92030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', '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 14:00:28 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 14:00:28 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 14:00:28 INFO - Using env: {'DISPLAY': ':0', 14:00:28 INFO - 'HOME': '/home/cltbld', 14:00:28 INFO - 'LANG': 'en_US.UTF-8', 14:00:28 INFO - 'LANGUAGE': 'en_US:en', 14:00:28 INFO - 'LOGNAME': 'cltbld', 14:00:28 INFO - 'MAIL': '/var/mail/cltbld', 14:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:28 INFO - 'MOZ_NO_REMOTE': '1', 14:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:28 INFO - 'NO_EM_RESTART': '1', 14:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:28 INFO - 'PWD': '/builds/slave/test', 14:00:28 INFO - 'SHELL': '/bin/bash', 14:00:28 INFO - 'SHLVL': '1', 14:00:28 INFO - 'TERM': 'linux', 14:00:28 INFO - 'TMOUT': '86400', 14:00:28 INFO - 'USER': 'cltbld', 14:00:28 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:28 INFO - '_': '/tools/buildbot/bin/python'} 14:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:00:28 INFO - Cleaning up... 14:00:28 INFO - Return code: 0 14:00:28 INFO - Running post-action listener: _resource_record_post_action 14:00:28 INFO - Running post-action listener: _start_resource_monitoring 14:00:28 INFO - Starting resource monitoring. 14:00:28 INFO - [mozharness: 2016-10-27 21:00:28.628436Z] Finished create-virtualenv step (success) 14:00:28 INFO - [mozharness: 2016-10-27 21:00:28.628866Z] Running install step. 14:00:28 INFO - Running pre-action listener: _resource_record_pre_action 14:00:28 INFO - Running main action method: install 14:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:28 INFO - Reading from file tmpfile_stdout 14:00:28 INFO - Detecting whether we're running mozinstall >=1.0... 14:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:00:28 INFO - Reading from file tmpfile_stdout 14:00:28 INFO - Output received: 14:00:28 INFO - Usage: mozinstall [options] installer 14:00:28 INFO - Options: 14:00:28 INFO - -h, --help show this help message and exit 14:00:28 INFO - -d DEST, --destination=DEST 14:00:28 INFO - Directory to install application into. [default: 14:00:28 INFO - "/builds/slave/test"] 14:00:28 INFO - --app=APP Application being installed. [default: firefox] 14:00:28 INFO - mkdir: /builds/slave/test/build/application 14:00:28 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'] 14:00:28 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 14:00:43 INFO - Reading from file tmpfile_stdout 14:00:43 INFO - Output received: 14:00:43 INFO - /builds/slave/test/build/application/firefox/firefox 14:00:43 INFO - Running post-action listener: _resource_record_post_action 14:00:43 INFO - [mozharness: 2016-10-27 21:00:43.111440Z] Finished install step (success) 14:00:43 INFO - [mozharness: 2016-10-27 21:00:43.111781Z] Running run-tests step. 14:00:43 INFO - Running pre-action listener: _resource_record_pre_action 14:00:43 INFO - Running main action method: run_tests 14:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:00:43 INFO - Python 2.7.3 14:00:43 INFO - Return code: 0 14:00:43 INFO - grabbing minidump binary from tooltool 14:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:43 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 0xe513b0>, '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 0xe522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe52740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:00:43 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 14:00:43 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 14:00:43 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 14:00:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:00:43 INFO - Return code: 0 14:00:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:00:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:00:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:00:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:00:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:00:43 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/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 14:00:43 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/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 14:00:43 INFO - Using env: {'DISPLAY': ':0', 14:00:43 INFO - 'HOME': '/home/cltbld', 14:00:43 INFO - 'LANG': 'en_US.UTF-8', 14:00:43 INFO - 'LANGUAGE': 'en_US:en', 14:00:43 INFO - 'LOGNAME': 'cltbld', 14:00:43 INFO - 'MAIL': '/var/mail/cltbld', 14:00:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:00:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:43 INFO - 'MOZ_NO_REMOTE': '1', 14:00:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:00:43 INFO - 'NO_EM_RESTART': '1', 14:00:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:43 INFO - 'PWD': '/builds/slave/test', 14:00:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:00:43 INFO - 'SHELL': '/bin/bash', 14:00:43 INFO - 'SHLVL': '1', 14:00:43 INFO - 'TERM': 'linux', 14:00:43 INFO - 'TMOUT': '86400', 14:00:43 INFO - 'USER': 'cltbld', 14:00:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426', 14:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:43 INFO - '_': '/tools/buildbot/bin/python'} 14:00:43 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/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 14:00:43 INFO - mozversion application_buildid: 20161027110534 14:00:43 INFO - mozversion application_changeset: 829a3f99f2606759305e3db204185242566a4ca6 14:00:43 INFO - mozversion application_display_name: Firefox 14:00:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:00:43 INFO - mozversion application_name: Firefox 14:00:43 INFO - mozversion application_remotingname: firefox 14:00:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:00:43 INFO - mozversion application_vendor: Mozilla 14:00:43 INFO - mozversion application_version: 50.0 14:00:43 INFO - mozversion platform_buildid: 20161027110534 14:00:43 INFO - mozversion platform_changeset: 829a3f99f2606759305e3db204185242566a4ca6 14:00:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:00:43 INFO - mozversion platform_version: 50.0 14:00:43 INFO - using testdate: 1477602043 14:00:43 INFO - actual date: 1477602043 14:00:43 INFO - starting webserver on 'localhost:60193' 14:00:43 INFO - SUITE-START | Running 2 tests 14:00:43 INFO - TEST-START | dromaeo_css 14:00:43 INFO - operating with platform_type : linux_ 14:00:43 INFO - Initialising browser for dromaeo_css test... 14:00:43 INFO - TEST-INFO | started process 19688 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBMZpG8/profile http://localhost:60193/getInfo.html) 14:00:47 INFO - PROCESS | 19688 | __metrics Screen width/height:1600/1200 14:00:47 INFO - PROCESS | 19688 | colorDepth:24 14:00:47 INFO - PROCESS | 19688 | Browser inner width/height: 1024/697 14:00:47 INFO - PROCESS | 19688 | __metrics 14:00:48 INFO - PROCESS | 19688 | 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" 14:00:48 INFO - PROCESS | 19688 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:00:48 INFO - TEST-INFO | 19688: exit 0 14:00:48 INFO - Browser initialized. 14:00:48 INFO - Running cycle 1/1 for dromaeo_css test... 14:00:48 INFO - TEST-INFO | started process 19780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBMZpG8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:01:00 INFO - PROCESS | 19780 | RSS: Main: 144441344 14:01:00 INFO - PROCESS | 19780 | 14:01:51 INFO - PROCESS | 19780 | 1477602111387 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] 14:04:00 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60193/tests/dromaeo/cssquery-ext.html) 14:04:01 INFO - PROCESS | 19780 | RSS: Main: 236433408 14:04:01 INFO - PROCESS | 19780 | 14:07:03 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-ext.html (next: http://localhost:60193/tests/dromaeo/cssquery-jquery.html) 14:07:04 INFO - PROCESS | 19780 | RSS: Main: 242061312 14:07:04 INFO - PROCESS | 19780 | 14:10:06 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60193/tests/dromaeo/cssquery-mootools.html) 14:10:06 INFO - PROCESS | 19780 | RSS: Main: 177360896 14:10:06 INFO - PROCESS | 19780 | 14:13:08 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60193/tests/dromaeo/cssquery-prototype.html) 14:13:09 INFO - PROCESS | 19780 | RSS: Main: 177762304 14:13:09 INFO - PROCESS | 19780 | 14:16:11 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60193/tests/dromaeo/cssquery-yui.html) 14:16:12 INFO - PROCESS | 19780 | RSS: Main: 179466240 14:16:12 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | Cycle 1(1): loaded http://localhost:60193/tests/dromaeo/cssquery-yui.html (next: http://localhost:60193/tests/dromaeo/cssquery-dojo.html) 14:19:14 INFO - PROCESS | 19780 | RSS: Main: 265986048 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | __start_tp_report 14:19:14 INFO - PROCESS | 19780 | _x_x_mozilla_page_load 14:19:14 INFO - PROCESS | 19780 | _x_x_mozilla_page_load_details 14:19:14 INFO - PROCESS | 19780 | |i|pagename|runs| 14:19:14 INFO - PROCESS | 19780 | |0;dojo.html;3214.678532146785;3224.5485632011523;3230.9838450807756;3274.1814546363407;3279.852406641701;5589.049861523541;5677.772889084442;5679.261695979522;5687.402822703619;5697.03150464421;5613.691246981424;5679.602427830043;5680.9715951420185;5687.232223649806;5689.203511508382;5158.793648254065;5159.71621560814;5174.353205849269;5225.033368826779;5236.633435659505;5046.949530504703;5102.158143906251;5250.501202385767;5255.0803609368395;5292.894142117156;5087.186050231963;5111.718855462948;5120.308758317636;5140.177571588547;5141.511556402136;5106.872328191787;5149.382074151107;5220.530152286275;5224.582033437354;5251.973740131275;5024.346834911438;5057.266696329012;5150.43345232028;5169.56058735008;5182.481751824825;5497.257870187514;5507.228987941691;5521.033819081685;5548.472895075001;5558.805441809517;5923.733431995571;5977.5815692901515;6028.216331876872;6028.487578555828;6042;5912.379200183962;5984.281886173687;6051.152838602599;6058.242719660043;6060.33336333003;5674.801381951612;5712.742926568314;5738.5982981191755;5740.971295143581;5741.138829175616;6275.341089185661;6278.591891527036;6281.183446151971;6283.842903927347;6286.559940804192;5775.797847075416;5787.276590426197;5811.970940145272;6025.6083354581815;6032.819015429544;5961.195238642815;5966.254218222723;5966.403359663999;5990.610610310316;5996.1005849122985;5923.526117910556;5923.7926672566255;5923.851903702356;5924.555658325643;5929.673867937218;5803.82588522345;5916.319623243296;5925.9407405925385;5928.881422371528;5931.228940237742;4592.15045216636;4596.10375976688;4598.1033698429155;4598.586127248564;4605.516420775837;4052.4731784867777;4078.5513593504693;4123.072308730511;4129.587041295907;4184.28867092595;5672.574556908248;5676.744546495418;5680.573956953245;5687.829365119053;5725.112607545837;5968.432998865101;5984.431479009487;5984.640921544721;5987.251593550806;6031.788887388921;5604.159376093537;5621.8594535136945;5648.068068768607;5651.943480565224;5664.858378540487;5213.635045546851;5215.113430716788;5215.817446389434;5226.817061402832;5233.816816411408;6199.411055949678;6237.09562113491;6245.219347581552;6245.531585131133;6249.843753906098;5916.319623243382;5984.13230081627;5996.130561068708;6011.879762404815;6019.72911219005;6303.5902666328375;6343.207099112611;6370.840728981627;6379.075034120119;6380.904286435614;6028.367021462684;6030.48740857032;6033.5173186145885;6039.607425517327;6039.969800150971;5828.475437210672;5975.014122669711;5976.940230597638;6108.419700128481;6130.47890929276;5837.445442682938;5906.173135761082;5917.940820591739;5931.673757943182;5949.988501954765;5914.852128696645;5929.1995580597795;5930.940690593211;5935.020721581063;5939.4951429129005;5791.971040144772;5826.067829147316;5842.883142337045;5872.559558033079;5924.8815023700145;5990.265818045676;5991.462091887039;5999.640021598718;6027.638341699512;6035.698215089315;5836.416358364129;5841.707914604168;5841.94158058414;5846.649201047951;5855.970720146372 14:19:14 INFO - PROCESS | 19780 | |1;ext.html;598.6438069348736;627.8807026664934;649.6849028221312;660.2473180574145;676.2662511175374;35984.640153598455;39025.80487097564;47254.52745472549;48257.75871120639;48339;48872.26691599629;49293;49401.25898111531;49405.258921116125;49442.75278623611;8614.56927153643;9264.444133352004;9416.62333506659;9423.952880235589;9429.764255893624;10684.946575267151;11009.504572294227;11067.391293478855;11365.602203922865;11368.204225704205;11226.382548959804;11324.490397932113;11324.886751132506;11326.830097548544;11341.546338146465;11078.667639970814;11135.276207046598;11142.05292550126;11147;11148.331100133977;10700;10762.192835537366;10763.3541987481;10763.623273185402;10765.3540787553;45216;45487.317690234675;45527.08945821065;45593.088138237385;45651.086978260246;3621.818909054563;4082.6937979651348;4381.277089280265;4448.110377924396;4516.661250406232;34511.79208727708;34695.65304346975;34721.82639086814;35176.64823351785;36721.265574688354;4643.860684179446;4665.88335291617;4682.672212945095;4755.643326750507;4794.304825800239;350131;351382.2430887855;351507;352650;353233;57445.71277143587;57824.71087644535;57846;57973.71013144907;65728;36226.275474490365;36742;36969;37005.629943700755;69174;15892;16056;16082.597935051483;16094.517164485083;16095.114768687834;11075.390853502973;11693.415329233505;11945.641630751092;12052.156349055658;12069.57756478519;20002.29991950304;21027.78972210258;21084.156633734478;21116.89441552813;21164.682529762067;7217.278272172845;7442.725200911582;7470.514416562906;7500.962495187489;7598.354139898171;989.1048601016092;1078.0836289154156;1078.638656050232;1111.1888321525332;1128.4075860173475;33269.50095748566;35277.25424065103;35709.82145089258;35797.64202357995;35813.13754791732;1110.9111271098293;1149.1036991146918;1154.549725606997;1163.6392718257368;1229.8032314829584;18412.171452284678;18441.1701473434;18518;18630.906845465684;18656.720149197772;635247;640465;641373;641735;642429;65524.344756552775;65741;65947.67026164934;66115.33884661188;66351;1342.8321459817523;1410.189141243789;1429.4710956945996;1450.187894778927;1452.404514149215;34409.82795086008;35490.8225458871;35494.822525887204;35807.28385432329;36271;54719.72640136933;55695.72152139214;55850.72074639601;55902.16146757721;55930.720346399634;445817;445863;446088;446554;447969;31049.8447507761;31967;32038.519422209152;32057.839710801298;32082.19794505156;51315;51824.481755181965;52251.21623175732;52713.47286527239;52871.20693189682;42034;45552;46398.304025440324;46773.766131169126;47006.294905577124;44021.55978440175;44924.10151797011;45323.773381134204;46466.53533464622;46499.76750116228;44390.3341449885;45057.549424506644;46388.30417543672;46489;46491.53508465007;37510.43734343933;40253.597464026156;40372.59627403806;40971.59028409797;41386;30125.246868828453;30743;30779.53830692586;30812;31055 14:19:14 INFO - PROCESS | 19780 | |2;jquery.html;418.4267553451771;426.32120729504527;433.1899348218831;437.0712236497443;441.96022357987795;19031.904840475796;20602.89698551505;20808.583828323426;21718;21901.780982190172;19969.500762480944;20304.796952030476;20348.186072557117;20416.081276342564;20481.897590512064;5733.713314334287;5994.430529099728;5998.2802063752415;5998.9700051499685;6005.00917348637;5745.5690823188215;5771.163181338703;5838.065909454488;5858.912116318259;5870.765169393219;5840.474357307841;5847.5614328925285;5849.415058494138;5851.7074146292725;5851.912221316684;5660.2075709400715;5840.591158618899;5864.560157988168;5872.031114866042;5874.942510348135;5245.895082098375;5250.343707036621;5295.841124766263;5297.337832770903;5299.152135658314;24662.87668561646;24786.38034049145;24917.62623560648;24957.5008499829;25071;2502.699676038869;2550.553653110717;2628.342914271432;2635.64418803461;2692.8115031947577;14775.852241477663;15101.169435681033;15356.155411452366;15428.074315541104;15855.127967961758;1978.3273686946363;2066.8863212523306;2088.7702352741185;2129.6486079796814;2147.76374598794;61935.380646193415;68206;71375.64312178458;76685.23314766893;78523.60738196272;11633.883661163449;11902.940485297691;11948.402579870972;12021.459034343476;12049.216800907912;15345.769813452807;16008.679826403406;16947.830521694872;16978.15109244533;17516.299348025936;8143.190599243251;8237.135100814328;8306;8393.706220282262;8400.747977560683;7718.92281077182;7738.264864837832;7764.456488045783;7925.326347260432;7927.563983980821;5040.218766091261;5122.487751224922;5143.794248230104;5264.1946126951;5288.550473209743;2024.4230394337544;2067.555475572759;2117.8517503774897;2161.1679503391306;2164.3290579920276;917.8623206518942;949.586929685589;991.3407583956588;1046.8063408269495;1067.236925598201;20489.48776280605;20719.689204661943;20999.790002099784;21118;21436.78563214379;1003.6537394598852;1010.888802231754;1014.8680671512656;1028.9691309260734;1037.5019990404649;11004.449777511092;11688.70778230551;11809.940950295193;11834.822477662841;11904.345261010727;508702;516351;517835;521997;523817;58875.70562147162;58987.41012589905;89596;95416.52291738497;96325.51837240909;936.4334577580602;958.2525630008465;964.9131578158;976.4190306767462;1007.969760907174;18080.819191807914;18186.909065454587;18401.90799045996;18515.3404839112;18862.905685471484;43651.34522982221;43831.561684382745;44082;44605;44747;57200.42799571951;58247.708761457616;58334.4166558346;58935.11597325958;61000.38999610124;8853.46879187225;8873.556322183736;9132.634694612141;9153.954230228806;9227.538623068685;7793.844123117619;10932.562697492329;12902.354882256037;13352;14862.628434289229;11969.190988913362;12161.209521381083;12445;12498;12618.369081545703;11729.94430501259;11943.925046745835;12185.878141218474;12193.69515762113;12581.496740130659;12017.158798883995;12839.679008024874;12998.415071321624;13052.934735326262;13056.869431305566;9428.15146636806;9478.431294122374;9524.714258572254;9669.323147379617;9714.805703886024;11423.143264255048;12524.749505010031;12544.686382840502;12661.620151395473;12807.743845123232 14:19:14 INFO - PROCESS | 19780 | |3;mootools.html;1450.4343306110611;1545.5981444824347;1575.0392260720962;1759.6480703859231;1867.2437662746584;8998.865017024742;9403.059694030602;9427.34008619396;9627.13355797978;9650.613975440974;9370.765730856714;9483;9484.52577371132;9497.952510237457;9500.524973751302;2432.2581612608146;2472.987635061831;2476.5913624252066;2480.1443501991785;2481.950360992779;2437.756224377557;2478.8264821462503;2481.4044629288933;2487.340854673513;2488.377905523619;2411.7708817662383;2426.2478631624163;2459.987700061506;2462.938426539333;2473.789727873133;2366.988165059181;2387.85672859629;2399.700037495313;2410;2443.0594221224956;2385.9522809543887;2401;2402.447437089462;2408.3617841272076;2408.397900524869;9257.907420925772;9436.150746432853;9626.37428567148;9700.466474343908;9702.805943881083;5337.70642614656;5441.5102640762525;5441.700706461143;5465.07093794052;5479;3443.126482706955;3494.9825250873837;3504.456809194579;3545.503629491873;3555.1112221944513;1171.3967306836985;1191.3149938785145;1225.4852961756085;1244.4897591987242;2111.1977448569446;13153.473861045546;13599;13605.659858503617;13659.521916732967;13663.18020918748;4512.729236245836;4768.3085952536685;4793.688410253392;4840.903181936342;4860.441049279308;10421.166306695446;10436.321639093434;10437.634682786218;10445.059944605022;10448;5508.724563771795;5540.612157149041;5544.473275038865;5545.362283337387;5549.139883318092;4615.100055489147;4730;4999.175136102503;5096.668716533413;5143.331366922276;4077;4082.1223436960604;4148.647364974011;4184.916301673949;4185.560516145822;1237.3565745812127;1239.8946089582487;1241.3172754984723;1248.2697621891296;1249.6875781054737;993.7863359377768;1032.4012072997643;1046.1264843855465;1049.5277125293496;1050.3172937590627;6110.602810817282;6374.553781235269;6383.648899310582;6439.452646525097;6512.283648798629;470.52241974396014;483.64935421819604;488.08484092326887;1071.9678409647722;1159.1422347462817;4336.436263285816;4364.716293440916;4377.78111094444;4517.774111294422;4675.532446755297;12011;12080.154389192849;12111.515539378503;12115.575954841715;12124.75750485003;11747.588834390755;12065.095117866194;12079.396030198455;12242.449089790802;12339.93830030844;8799.648014079365;8833.381663283637;8914.197722205032;8995.100489950952;9008.099190080939;6390.201224846895;6492.220933488012;6511.153550038464;6630.138082049302;6669.433098186709;10361.430121343097;10599.735006624896;10813.297135686154;10853.53477280557;10882.40146791934;10771.730706732395;11064.22550421495;11119.499622517167;11195.384253866116;11234.606788762354;8250.092489826235;8657.870131948152;8708.956455217683;8720.825583488422;8730.519821409884;9260.30547708911;9273.25813934897;9339.159475646953;9343.345965782602;9354.438733676001;8482.491050536988;8511.531865747196;8512.233898948876;8638.91361086381;8665.913340866511;8301.418900677136;8337.916620833714;8359.916400836157;8449.83100338002;8459.196376344238;7264.309890560388;7273.054502914587;7317.0487836580905;7322.560646361236;7341.082364704412;5525.336959564878;5637.49262566371;5651.265335506358;5689.317281926195;5757.050086735641;5531.170324451365;5665.688387138747;5734.111212762028;5786.971065144648;5852.824415267549 14:19:14 INFO - PROCESS | 19780 | |4;prototype.html;344.49015457123465;345.99481007784885;353.3798184186752;364.9489071529988;369.9038250054985;5470.206820011101;5507.118860982244;5533.0674742386545;5538.584606154534;5553.888922221553;5427.348718153818;5433.907945441004;5473;5474.753636086376;5480.3971563128025;3947.605239476058;4078.614010803676;4145.150244199932;4307.138572285539;4313.309870420733;4269.658427325807;4272.807723652428;4292.248856450125;4306.655467562588;4308.922769307673;4290.97854510727;4300.354946757996;4305.094061508485;4311.3748506466545;4342.371610646008;4078.714489985702;4243.087736136746;4323.178596066738;4325.178216138924;4342.50061242958;3763.73653844231;3775.263823554408;3776.8866933992026;3784.921297430219;3794.886153415402;5296.682199068068;5308.256844041837;5323.627346085776;5342.252084708144;5358.517733404013;4831.468538460802;4898.730569818658;4917.262410638397;4959.404871415453;4963.2058870580895;5597.132444471114;5656.547476201894;5662.631928924648;5671.291088613923;5676.8864622707315;4156.43888075109;4159.771212583307;4174.248635245654;4198.685098617585;4203.62167404932;5768.3654797972185;5912.999999999957;5989.39461334321;6011.278646562397;6018.608790428608;4191.91616167675;4199.580041995837;4200.004997626103;4216.367544868294;4237.194932962727;4284.978575107167;4285.571442855689;4291.9785401072795;4303.870883873489;4306.20335237982;4735.95264047362;4912;4923.556879880756;4933.914538801458;4948.480409557017;5716.828495145152;5721.456461636138;5724.570657200727;5756.971215143897;5806.912896306559;1454.5563603101157;1472.5729538433734;1473.690524989764;1474.7050589882067;1485.5617592810147;1000.2947921715173;1025.8102251083574;1031.4224034540682;1034.534459493216;1054.7995880782628;5524.806631767869;5545.944540554624;5555.666620019601;5606.803761868315;5625.212470254168;5908;5916.2013128228;5933.262044911764;5942.524598032147;5978.372270911578;5436.483534064258;5506.577106073315;5541.224228607998;5561.777528898799;5604.327480702342;2673.625692403065;2752.6696796384563;2753.9449211016067;2771.473420050184;2781.8330900146057;5121.897562048738;5274.7626356814035;5402.37872644643;5406.135018397038;5437.265969094201;5167.560757335594;5198.870028249324;5226.869328266747;5256.316678831804;5281.181416880389;5781.88436231273;5814.796482123105;5934.3383852521365;5956.404359564096;6043.305019922677;5832.62087964281;5847.3335099496435;5848.4151584841175;5872.383399742966;5887.852803679943;5574.526165275997;5736.282964629421;5764.510016648632;5779.2198053263955;5791.884162316645;6017.18767966319;6042.274927008787;6057.454829065406;6057.636541807506;6067.332593414809;4086.673066154641;4127.814248358771;4135.58644135584;4188.748675079505;4200.873973780792;5761.473209599376;5831.562632802472;5842.970785146047;5873.706314684163;5979.4917432018765;5698.914516282176;5716.199732037435;5761.366249712444;5763.337216220189;5764.682942438206;5570.387257401763;5714.3999880012;5718.228039214653;5725.854829034293;5734.34055083654;5684.289463817023;5692.943070569241;5705.286839145107;5707.68607726579;5708.80019199326;5723.909089091844;5738.770449182153;5754.510866576388;5757.65454072757;5770.451807078321;5385.784568617211;5530.336359636869;5584.609077364708;5622.3815380307315;5629.155626656035 14:19:14 INFO - PROCESS | 19780 | |5;yui.html;773.8220209351847;806.6894245715398;808.2442915873659;841.8653015517517;899.181744612402;16389.60688341491;17403.868748531353;18036.729449058243;18181.27274909002;18196.363127290548;16239.594010149753;16454.75317870233;16477.093759843203;16483.258253378597;16489;738.4683028219673;858.549261637639;871.8038441350693;879.4020066354876;881.1056777370974;857.9227869491745;871.3682580129419;872.7730789994615;872.7861673889906;873.0309356614154;928.5032507608431;929.1359036096428;929.7210836748948;929.7722058095742;930.2465003347277;916.0152835701581;917.7705573606598;921.2629896083107;922.008840496469;922.9400088994194;673.8719643267764;675.787127054976;676.0737789228788;677.0318444624186;677.5968298862169;15471.226438678133;15475;15497.380104795795;15508.224588770629;15661.608459788482;16172.956760809797;16377.59056023597;16716.16419179036;16822.495325140266;17074;13387.598372048855;13397.464101435931;13399.866001339959;13426.865731342757;13611.251381174032;4132.173565286955;4135.627793498569;4143.854965076238;4157.563455837124;4218.472690913636;83094;86321.56839215827;86885.56557217173;86947;88746;12767.680807979688;12974.935125324502;13044.47822087125;13081.803772943413;13166.275854827885;23732;23864;24073.398165045663;24123.879380602986;24332.75667243305;9218.76953076165;9239.30705197113;9254.305927055364;9342.159205671522;9360.34477586576;4651.953480465219;4986.875328116754;4995.825146119869;5042.243663450512;5252.395974462909;3850.403187505885;3971.801409929492;3973.264945985002;3993.800309984489;3998.620131087542;599.1701493431628;678.2979616097316;687.6149356360454;690.2614202803053;691.4364792693939;631.5421319543294;674.8312921769558;715.7709532949406;766.5783818899583;816.6774124220899;10055.648052318134;10708.19688523364;10847.240693151562;10875.238733288752;11165.72085697864;701.0885848397063;738.6860584251779;739.4306384084225;772.5441989226383;806.7983004248938;4769.59458446029;4936.827211047668;4976.42771081323;4990.2764099205415;5127.256547800548;128279;128465.35767321104;130530;132157.07057576577;134225.328873355;111862;113651;115524;117136.41431792786;117216.41391792987;91954.54022729844;97558;98319.50840245753;99253.50373247942;99621.50189249008;9723.805523889625;9797.31418800677;9897.455639939872;9944.30389872702;9977.750556236153;56696.433035670765;56742.716286418305;57374;58038.70980645239;58320.70839645775;80479.19520804717;83382;84985;85380;88069;10680.092192163753;10942.288751231144;11030.06244469229;13291.468341266242;13835.723285534434;14047.719045619235;14393.856061439252;14534.273286335852;14613.19627420502;15260.771088433905;12529.18560293578;12615.116941813985;12765.170263932814;13070.607881763563;13611.25138117413;14450.85549144495;14811.481598144013;14823.431575404224;14912.105273683614;15076.170810605521;13763.587092387244;14638.634034149232;14702.77945830792;14811.555653330417;14886.925565372103;11016.986958655358;11072.501737421997;11088.611898583511;11320.207585469267;11745.706357341134;11853;12560.937195313965;12747.553835615709;12836.486540538273;12986.610401687587 14:19:14 INFO - PROCESS | 19780 | __end_tp_report 14:19:14 INFO - PROCESS | 19780 | __start_cc_report 14:19:14 INFO - PROCESS | 19780 | _x_x_mozilla_cycle_collect,889 14:19:14 INFO - PROCESS | 19780 | __end_cc_report 14:19:14 INFO - PROCESS | 19780 | __startTimestamp1477603154657__endTimestamp 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | ------- Summary: start ------- 14:19:14 INFO - PROCESS | 19780 | Number of tests: 6 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#0] dojo.html Cycles:165 Average:5606.98 Median:5836.42 stddev:637.04 (10.9%) stddev-sans-first:610.72 14:19:14 INFO - PROCESS | 19780 | Values: 3214.7 3224.5 3231.0 3274.2 3279.9 5589.0 5677.8 5679.3 5687.4 5697.0 5613.7 5679.6 5681.0 5687.2 5689.2 5158.8 5159.7 5174.4 5225.0 5236.6 5046.9 5102.2 5250.5 5255.1 5292.9 5087.2 5111.7 5120.3 5140.2 5141.5 5106.9 5149.4 5220.5 5224.6 5252.0 5024.3 5057.3 5150.4 5169.6 5182.5 5497.3 5507.2 5521.0 5548.5 5558.8 5923.7 5977.6 6028.2 6028.5 6042.0 5912.4 5984.3 6051.2 6058.2 6060.3 5674.8 5712.7 5738.6 5741.0 5741.1 6275.3 6278.6 6281.2 6283.8 6286.6 5775.8 5787.3 5812.0 6025.6 6032.8 5961.2 5966.3 5966.4 5990.6 5996.1 5923.5 5923.8 5923.9 5924.6 5929.7 5803.8 5916.3 5925.9 5928.9 5931.2 4592.2 4596.1 4598.1 4598.6 4605.5 4052.5 4078.6 4123.1 4129.6 4184.3 5672.6 5676.7 5680.6 5687.8 5725.1 5968.4 5984.4 5984.6 5987.3 6031.8 5604.2 5621.9 5648.1 5651.9 5664.9 5213.6 5215.1 5215.8 5226.8 5233.8 6199.4 6237.1 6245.2 6245.5 6249.8 5916.3 5984.1 5996.1 6011.9 6019.7 6303.6 6343.2 6370.8 6379.1 6380.9 6028.4 6030.5 6033.5 6039.6 6040.0 5828.5 5975.0 5976.9 6108.4 6130.5 5837.4 5906.2 5917.9 5931.7 5950.0 5914.9 5929.2 5930.9 5935.0 5939.5 5792.0 5826.1 5842.9 5872.6 5924.9 5990.3 5991.5 5999.6 6027.6 6035.7 5836.4 5841.7 5841.9 5846.6 5856.0 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#1] ext.html Cycles:180 Average:65000.51 Median:38268.12 stddev:131437.37 (343.5%) stddev-sans-first:131717.14 14:19:14 INFO - PROCESS | 19780 | Values: 598.6 627.9 649.7 660.2 676.3 35984.6 39025.8 47254.5 48257.8 48339.0 48872.3 49293.0 49401.3 49405.3 49442.8 8614.6 9264.4 9416.6 9424.0 9429.8 10684.9 11009.5 11067.4 11365.6 11368.2 11226.4 11324.5 11324.9 11326.8 11341.5 11078.7 11135.3 11142.1 11147.0 11148.3 10700.0 10762.2 10763.4 10763.6 10765.4 45216.0 45487.3 45527.1 45593.1 45651.1 3621.8 4082.7 4381.3 4448.1 4516.7 34511.8 34695.7 34721.8 35176.6 36721.3 4643.9 4665.9 4682.7 4755.6 4794.3 350131.0 351382.2 351507.0 352650.0 353233.0 57445.7 57824.7 57846.0 57973.7 65728.0 36226.3 36742.0 36969.0 37005.6 69174.0 15892.0 16056.0 16082.6 16094.5 16095.1 11075.4 11693.4 11945.6 12052.2 12069.6 20002.3 21027.8 21084.2 21116.9 21164.7 7217.3 7442.7 7470.5 7501.0 7598.4 989.1 1078.1 1078.6 1111.2 1128.4 33269.5 35277.3 35709.8 35797.6 35813.1 1110.9 1149.1 1154.5 1163.6 1229.8 18412.2 18441.2 18518.0 18630.9 18656.7 635247.0 640465.0 641373.0 641735.0 642429.0 65524.3 65741.0 65947.7 66115.3 66351.0 1342.8 1410.2 1429.5 1450.2 1452.4 34409.8 35490.8 35494.8 35807.3 36271.0 54719.7 55695.7 55850.7 55902.2 55930.7 445817.0 445863.0 446088.0 446554.0 447969.0 31049.8 31967.0 32038.5 32057.8 32082.2 51315.0 51824.5 52251.2 52713.5 52871.2 42034.0 45552.0 46398.3 46773.8 47006.3 44021.6 44924.1 45323.8 46466.5 46499.8 44390.3 45057.5 46388.3 46489.0 46491.5 37510.4 40253.6 40372.6 40971.6 41386.0 30125.2 30743.0 30779.5 30812.0 31055.0 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#2] jquery.html Cycles:180 Average:29807.32 Median:2664.23 stddev:84828.14 (3184.0%) stddev-sans-first:85037.40 14:19:14 INFO - PROCESS | 19780 | Values: 418.4 426.3 433.2 437.1 442.0 19031.9 20602.9 20808.6 21718.0 21901.8 19969.5 20304.8 20348.2 20416.1 20481.9 5733.7 5994.4 5998.3 5999.0 6005.0 5745.6 5771.2 5838.1 5858.9 5870.8 5840.5 5847.6 5849.4 5851.7 5851.9 5660.2 5840.6 5864.6 5872.0 5874.9 5245.9 5250.3 5295.8 5297.3 5299.2 24662.9 24786.4 24917.6 24957.5 25071.0 2502.7 2550.6 2628.3 2635.6 2692.8 14775.9 15101.2 15356.2 15428.1 15855.1 1978.3 2066.9 2088.8 2129.6 2147.8 61935.4 68206.0 71375.6 76685.2 78523.6 11633.9 11902.9 11948.4 12021.5 12049.2 15345.8 16008.7 16947.8 16978.2 17516.3 8143.2 8237.1 8306.0 8393.7 8400.7 7718.9 7738.3 7764.5 7925.3 7927.6 5040.2 5122.5 5143.8 5264.2 5288.6 2024.4 2067.6 2117.9 2161.2 2164.3 917.9 949.6 991.3 1046.8 1067.2 20489.5 20719.7 20999.8 21118.0 21436.8 1003.7 1010.9 1014.9 1029.0 1037.5 11004.4 11688.7 11809.9 11834.8 11904.3 508702.0 516351.0 517835.0 521997.0 523817.0 58875.7 58987.4 89596.0 95416.5 96325.5 936.4 958.3 964.9 976.4 1008.0 18080.8 18186.9 18401.9 18515.3 18862.9 43651.3 43831.6 44082.0 44605.0 44747.0 57200.4 58247.7 58334.4 58935.1 61000.4 8853.5 8873.6 9132.6 9154.0 9227.5 7793.8 10932.6 12902.4 13352.0 14862.6 11969.2 12161.2 12445.0 12498.0 12618.4 11729.9 11943.9 12185.9 12193.7 12581.5 12017.2 12839.7 12998.4 13052.9 13056.9 9428.2 9478.4 9524.7 9669.3 9714.8 11423.1 12524.7 12544.7 12661.6 12807.7 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#3] mootools.html Cycles:180 Average:6241.66 Median:4566.44 stddev:3616.88 (79.2%) stddev-sans-first:3609.10 14:19:14 INFO - PROCESS | 19780 | Values: 1450.4 1545.6 1575.0 1759.6 1867.2 8998.9 9403.1 9427.3 9627.1 9650.6 9370.8 9483.0 9484.5 9498.0 9500.5 2432.3 2473.0 2476.6 2480.1 2482.0 2437.8 2478.8 2481.4 2487.3 2488.4 2411.8 2426.2 2460.0 2462.9 2473.8 2367.0 2387.9 2399.7 2410.0 2443.1 2386.0 2401.0 2402.4 2408.4 2408.4 9257.9 9436.2 9626.4 9700.5 9702.8 5337.7 5441.5 5441.7 5465.1 5479.0 3443.1 3495.0 3504.5 3545.5 3555.1 1171.4 1191.3 1225.5 1244.5 2111.2 13153.5 13599.0 13605.7 13659.5 13663.2 4512.7 4768.3 4793.7 4840.9 4860.4 10421.2 10436.3 10437.6 10445.1 10448.0 5508.7 5540.6 5544.5 5545.4 5549.1 4615.1 4730.0 4999.2 5096.7 5143.3 4077.0 4082.1 4148.6 4184.9 4185.6 1237.4 1239.9 1241.3 1248.3 1249.7 993.8 1032.4 1046.1 1049.5 1050.3 6110.6 6374.6 6383.6 6439.5 6512.3 470.5 483.6 488.1 1072.0 1159.1 4336.4 4364.7 4377.8 4517.8 4675.5 12011.0 12080.2 12111.5 12115.6 12124.8 11747.6 12065.1 12079.4 12242.4 12339.9 8799.6 8833.4 8914.2 8995.1 9008.1 6390.2 6492.2 6511.2 6630.1 6669.4 10361.4 10599.7 10813.3 10853.5 10882.4 10771.7 11064.2 11119.5 11195.4 11234.6 8250.1 8657.9 8709.0 8720.8 8730.5 9260.3 9273.3 9339.2 9343.3 9354.4 8482.5 8511.5 8512.2 8638.9 8665.9 8301.4 8337.9 8359.9 8449.8 8459.2 7264.3 7273.1 7317.0 7322.6 7341.1 5525.3 5637.5 5651.3 5689.3 5757.1 5531.2 5665.7 5734.1 5787.0 5852.8 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#4] prototype.html Cycles:180 Average:4780.72 Median:5430.63 stddev:1397.36 (25.7%) stddev-sans-first:1361.03 14:19:14 INFO - PROCESS | 19780 | Values: 344.5 346.0 353.4 364.9 369.9 5470.2 5507.1 5533.1 5538.6 5553.9 5427.3 5433.9 5473.0 5474.8 5480.4 3947.6 4078.6 4145.2 4307.1 4313.3 4269.7 4272.8 4292.2 4306.7 4308.9 4291.0 4300.4 4305.1 4311.4 4342.4 4078.7 4243.1 4323.2 4325.2 4342.5 3763.7 3775.3 3776.9 3784.9 3794.9 5296.7 5308.3 5323.6 5342.3 5358.5 4831.5 4898.7 4917.3 4959.4 4963.2 5597.1 5656.5 5662.6 5671.3 5676.9 4156.4 4159.8 4174.2 4198.7 4203.6 5768.4 5913.0 5989.4 6011.3 6018.6 4191.9 4199.6 4200.0 4216.4 4237.2 4285.0 4285.6 4292.0 4303.9 4306.2 4736.0 4912.0 4923.6 4933.9 4948.5 5716.8 5721.5 5724.6 5757.0 5806.9 1454.6 1472.6 1473.7 1474.7 1485.6 1000.3 1025.8 1031.4 1034.5 1054.8 5524.8 5545.9 5555.7 5606.8 5625.2 5908.0 5916.2 5933.3 5942.5 5978.4 5436.5 5506.6 5541.2 5561.8 5604.3 2673.6 2752.7 2753.9 2771.5 2781.8 5121.9 5274.8 5402.4 5406.1 5437.3 5167.6 5198.9 5226.9 5256.3 5281.2 5781.9 5814.8 5934.3 5956.4 6043.3 5832.6 5847.3 5848.4 5872.4 5887.9 5574.5 5736.3 5764.5 5779.2 5791.9 6017.2 6042.3 6057.5 6057.6 6067.3 4086.7 4127.8 4135.6 4188.7 4200.9 5761.5 5831.6 5843.0 5873.7 5979.5 5698.9 5716.2 5761.4 5763.3 5764.7 5570.4 5714.4 5718.2 5725.9 5734.3 5684.3 5692.9 5705.3 5707.7 5708.8 5723.9 5738.8 5754.5 5757.7 5770.5 5385.8 5530.3 5584.6 5622.4 5629.2 14:19:14 INFO - PROCESS | 19780 | 14:19:14 INFO - PROCESS | 19780 | [#5] yui.html Cycles:180 Average:23221.18 Median:4133.90 stddev:34241.99 (828.3%) stddev-sans-first:34296.56 14:19:14 INFO - PROCESS | 19780 | Values: 773.8 806.7 808.2 841.9 899.2 16389.6 17403.9 18036.7 18181.3 18196.4 16239.6 16454.8 16477.1 16483.3 16489.0 738.5 858.5 871.8 879.4 881.1 857.9 871.4 872.8 872.8 873.0 928.5 929.1 929.7 929.8 930.2 916.0 917.8 921.3 922.0 922.9 673.9 675.8 676.1 677.0 677.6 15471.2 15475.0 15497.4 15508.2 15661.6 16173.0 16377.6 16716.2 16822.5 17074.0 13387.6 13397.5 13399.9 13426.9 13611.3 4132.2 4135.6 4143.9 4157.6 4218.5 83094.0 86321.6 86885.6 86947.0 88746.0 12767.7 12974.9 13044.5 13081.8 13166.3 23732.0 23864.0 24073.4 24123.9 24332.8 9218.8 9239.3 9254.3 9342.2 9360.3 4652.0 4986.9 4995.8 5042.2 5252.4 3850.4 3971.8 3973.3 3993.8 3998.6 599.2 678.3 687.6 690.3 691.4 631.5 674.8 715.8 766.6 816.7 10055.6 10708.2 10847.2 10875.2 11165.7 701.1 738.7 739.4 772.5 806.8 4769.6 4936.8 4976.4 4990.3 5127.3 128279.0 128465.4 130530.0 132157.1 134225.3 111862.0 113651.0 115524.0 117136.4 117216.4 91954.5 97558.0 98319.5 99253.5 99621.5 9723.8 9797.3 9897.5 9944.3 9977.8 56696.4 56742.7 57374.0 58038.7 58320.7 80479.2 83382.0 84985.0 85380.0 88069.0 10680.1 10942.3 11030.1 13291.5 13835.7 14047.7 14393.9 14534.3 14613.2 15260.8 12529.2 12615.1 12765.2 13070.6 13611.3 14450.9 14811.5 14823.4 14912.1 15076.2 13763.6 14638.6 14702.8 14811.6 14886.9 11017.0 11072.5 11088.6 11320.2 11745.7 11853.0 12560.9 12747.6 12836.5 12986.6 14:19:14 INFO - PROCESS | 19780 | -------- Summary: end -------- 14:19:14 INFO - PROCESS | 19780 | 14:19:15 INFO - TEST-INFO | 19780: exit 0 14:19:15 INFO - TEST-OK | dromaeo_css | took 1112046ms 14:19:15 INFO - TEST-START | kraken 14:19:15 INFO - operating with platform_type : linux_ 14:19:15 INFO - Initialising browser for kraken test... 14:19:15 INFO - TEST-INFO | started process 19976 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvWwS9u/profile http://localhost:60193/getInfo.html) 14:19:19 INFO - PROCESS | 19976 | __metrics Screen width/height:1600/1200 14:19:19 INFO - PROCESS | 19976 | colorDepth:24 14:19:19 INFO - PROCESS | 19976 | Browser inner width/height: 1024/697 14:19:19 INFO - PROCESS | 19976 | __metrics 14:19:19 INFO - PROCESS | 19976 | 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" 14:19:19 INFO - PROCESS | 19976 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:19:20 INFO - PROCESS | 19976 | console.error: PushService: 14:19:20 INFO - PROCESS | 19976 | stateChangeProcessEnqueue: Error transitioning state 14:19:20 INFO - PROCESS | 19976 | UnknownError 14:19:20 INFO - TEST-INFO | 19976: exit 0 14:19:20 INFO - Browser initialized. 14:19:20 INFO - Running cycle 1/1 for kraken test... 14:19:20 INFO - TEST-INFO | started process 20065 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvWwS9u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:32 INFO - PROCESS | 20065 | RSS: Main: 144019456 14:19:32 INFO - PROCESS | 20065 | 14:19:35 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60193/tests/kraken/driver.html?testName=audio-beat-detection) 14:19:35 INFO - PROCESS | 20065 | RSS: Main: 199270400 14:19:35 INFO - PROCESS | 20065 | 14:19:38 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60193/tests/kraken/driver.html?testName=audio-dft) 14:19:39 INFO - PROCESS | 20065 | RSS: Main: 183582720 14:19:39 INFO - PROCESS | 20065 | 14:19:41 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60193/tests/kraken/driver.html?testName=audio-fft) 14:19:42 INFO - PROCESS | 20065 | RSS: Main: 274210816 14:19:42 INFO - PROCESS | 20065 | 14:19:44 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60193/tests/kraken/driver.html?testName=audio-oscillator) 14:19:45 INFO - PROCESS | 20065 | RSS: Main: 180047872 14:19:45 INFO - PROCESS | 20065 | 14:19:47 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60193/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:19:47 INFO - PROCESS | 20065 | RSS: Main: 179273728 14:19:47 INFO - PROCESS | 20065 | 14:19:50 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60193/tests/kraken/driver.html?testName=imaging-darkroom) 14:19:51 INFO - PROCESS | 20065 | RSS: Main: 215351296 14:19:51 INFO - PROCESS | 20065 | 14:19:54 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60193/tests/kraken/driver.html?testName=imaging-desaturate) 14:19:55 INFO - PROCESS | 20065 | RSS: Main: 213508096 14:19:55 INFO - PROCESS | 20065 | 14:19:58 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60193/tests/kraken/driver.html?testName=json-parse-financial) 14:19:58 INFO - PROCESS | 20065 | RSS: Main: 214208512 14:19:58 INFO - PROCESS | 20065 | 14:20:00 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60193/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:20:00 INFO - PROCESS | 20065 | RSS: Main: 175996928 14:20:00 INFO - PROCESS | 20065 | 14:20:02 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:20:03 INFO - PROCESS | 20065 | RSS: Main: 182820864 14:20:03 INFO - PROCESS | 20065 | 14:20:05 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:20:05 INFO - PROCESS | 20065 | RSS: Main: 189407232 14:20:05 INFO - PROCESS | 20065 | 14:20:08 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:20:08 INFO - PROCESS | 20065 | RSS: Main: 192303104 14:20:08 INFO - PROCESS | 20065 | 14:20:11 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:20:12 INFO - PROCESS | 20065 | RSS: Main: 193687552 14:20:12 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | Cycle 1(1): loaded http://localhost:60193/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60193/tests/kraken/driver.html?testName=ai-astar) 14:20:13 INFO - PROCESS | 20065 | RSS: Main: 244617216 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | __start_tp_report 14:20:13 INFO - PROCESS | 20065 | _x_x_mozilla_page_load 14:20:13 INFO - PROCESS | 20065 | _x_x_mozilla_page_load_details 14:20:13 INFO - PROCESS | 20065 | |i|pagename|runs| 14:20:13 INFO - PROCESS | 20065 | |0;ai-astar;112;110;120;111;113;110;110;111;111;111 14:20:13 INFO - PROCESS | 20065 | |1;audio-beat-detection;142;115;147;173;115;143;144;121;176;115 14:20:13 INFO - PROCESS | 20065 | |2;audio-dft;159;161;161;161;160;161;162;162;160;160 14:20:13 INFO - PROCESS | 20065 | |3;audio-fft;76;75;75;186;75;75;76;75;179;74 14:20:13 INFO - PROCESS | 20065 | |4;audio-oscillator;83;83;169;83;83;83;144;83;84;147 14:20:13 INFO - PROCESS | 20065 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;117 14:20:13 INFO - PROCESS | 20065 | |6;imaging-darkroom;146;146;145;146;145;145;146;146;147;147 14:20:13 INFO - PROCESS | 20065 | |7;imaging-desaturate;94;95;95;95;95;94;95;95;95;95 14:20:13 INFO - PROCESS | 20065 | |8;json-parse-financial;56;49;49;50;51;50;52;50;51;49 14:20:13 INFO - PROCESS | 20065 | |9;json-stringify-tinderbox;60;61;61;60;60;60;61;61;61;60 14:20:13 INFO - PROCESS | 20065 | |10;stanford-crypto-aes;76;77;72;73;77;81;74;78;74;79 14:20:13 INFO - PROCESS | 20065 | |11;stanford-crypto-ccm;132;123;126;121;126;125;117;129;170;126 14:20:13 INFO - PROCESS | 20065 | |12;stanford-crypto-pbkdf2;151;151;153;149;150;149;149;154;148;151 14:20:13 INFO - PROCESS | 20065 | |13;stanford-crypto-sha256-iterative;64;61;64;61;62;60;60;61;66;59 14:20:13 INFO - PROCESS | 20065 | __end_tp_report 14:20:13 INFO - PROCESS | 20065 | __start_cc_report 14:20:13 INFO - PROCESS | 20065 | _x_x_mozilla_cycle_collect,1654 14:20:13 INFO - PROCESS | 20065 | __end_cc_report 14:20:13 INFO - PROCESS | 20065 | __startTimestamp1477603213946__endTimestamp 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | ------- Summary: start ------- 14:20:13 INFO - PROCESS | 20065 | Number of tests: 14 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.00 stddev:3.00 (2.7%) stddev-sans-first:3.18 14:20:13 INFO - PROCESS | 20065 | Values: 112.0 110.0 120.0 111.0 113.0 110.0 110.0 111.0 111.0 111.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:143.50 stddev:22.83 (15.9%) stddev-sans-first:24.19 14:20:13 INFO - PROCESS | 20065 | Values: 142.0 115.0 147.0 173.0 115.0 143.0 144.0 121.0 176.0 115.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.78 14:20:13 INFO - PROCESS | 20065 | Values: 159.0 161.0 161.0 161.0 160.0 161.0 162.0 162.0 160.0 160.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#3] audio-fft Cycles:10 Average:96.60 Median:75.00 stddev:45.31 (60.4%) stddev-sans-first:47.44 14:20:13 INFO - PROCESS | 20065 | Values: 76.0 75.0 75.0 186.0 75.0 75.0 76.0 75.0 179.0 74.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:83.00 stddev:34.51 (41.6%) stddev-sans-first:35.74 14:20:13 INFO - PROCESS | 20065 | Values: 83.0 83.0 169.0 83.0 83.0 83.0 144.0 83.0 84.0 147.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:20:13 INFO - PROCESS | 20065 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 14:20:13 INFO - PROCESS | 20065 | Values: 146.0 146.0 145.0 146.0 145.0 145.0 146.0 146.0 147.0 147.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 14:20:13 INFO - PROCESS | 20065 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.50 stddev:2.11 (4.2%) stddev-sans-first:1.05 14:20:13 INFO - PROCESS | 20065 | Values: 56.0 49.0 49.0 50.0 51.0 50.0 52.0 50.0 51.0 49.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 14:20:13 INFO - PROCESS | 20065 | Values: 60.0 61.0 61.0 60.0 60.0 60.0 61.0 61.0 61.0 60.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:77.00 stddev:2.85 (3.7%) stddev-sans-first:3.02 14:20:13 INFO - PROCESS | 20065 | Values: 76.0 77.0 72.0 73.0 77.0 81.0 74.0 78.0 74.0 79.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:126.00 stddev:14.81 (11.8%) stddev-sans-first:15.68 14:20:13 INFO - PROCESS | 20065 | Values: 132.0 123.0 126.0 121.0 126.0 125.0 117.0 129.0 170.0 126.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.50 Median:151.00 stddev:1.90 (1.3%) stddev-sans-first:2.01 14:20:13 INFO - PROCESS | 20065 | Values: 151.0 151.0 153.0 149.0 150.0 149.0 149.0 154.0 148.0 151.0 14:20:13 INFO - PROCESS | 20065 | 14:20:13 INFO - PROCESS | 20065 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:2.20 (3.6%) stddev-sans-first:2.19 14:20:13 INFO - PROCESS | 20065 | Values: 64.0 61.0 64.0 61.0 62.0 60.0 60.0 61.0 66.0 59.0 14:20:13 INFO - PROCESS | 20065 | -------- Summary: end -------- 14:20:13 INFO - PROCESS | 20065 | 14:20:14 INFO - TEST-INFO | 20065: exit 0 14:20:14 INFO - TEST-OK | kraken | took 59530ms 14:20:14 INFO - SUITE-END | took 1171s 14:20:15 INFO - Completed test suite (00:19:31) 14:20:15 INFO - javascript benchmark 14:20:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3214.678532146785, 3224.5485632011523, 3230.9838450807756, 3274.1814546363407, 3279.852406641701, 5589.049861523541, 5677.772889084442, 5679.261695979522, 5687.402822703619, 5697.03150464421, 5613.691246981424, 5679.602427830043, 5680.9715951420185, 5687.232223649806, 5689.203511508382, 5158.793648254065, 5159.71621560814, 5174.353205849269, 5225.033368826779, 5236.633435659505, 5046.949530504703, 5102.158143906251, 5250.501202385767, 5255.0803609368395, 5292.894142117156, 5087.186050231963, 5111.718855462948, 5120.308758317636, 5140.177571588547, 5141.511556402136, 5106.872328191787, 5149.382074151107, 5220.530152286275, 5224.582033437354, 5251.973740131275, 5024.346834911438, 5057.266696329012, 5150.43345232028, 5169.56058735008, 5182.481751824825, 5497.257870187514, 5507.228987941691, 5521.033819081685, 5548.472895075001, 5558.805441809517, 5923.733431995571, 5977.5815692901515, 6028.216331876872, 6028.487578555828, 6042.0, 5912.379200183962, 5984.281886173687, 6051.152838602599, 6058.242719660043, 6060.33336333003, 5674.801381951612, 5712.742926568314, 5738.5982981191755, 5740.971295143581, 5741.138829175616, 6275.341089185661, 6278.591891527036, 6281.183446151971, 6283.842903927347, 6286.559940804192, 5775.797847075416, 5787.276590426197, 5811.970940145272, 6025.6083354581815, 6032.819015429544, 5961.195238642815, 5966.254218222723, 5966.403359663999, 5990.610610310316, 5996.1005849122985, 5923.526117910556, 5923.7926672566255, 5923.851903702356, 5924.555658325643, 5929.673867937218, 5803.82588522345, 5916.319623243296, 5925.9407405925385, 5928.881422371528, 5931.228940237742, 4592.15045216636, 4596.10375976688, 4598.1033698429155, 4598.586127248564, 4605.516420775837, 4052.4731784867777, 4078.5513593504693, 4123.072308730511, 4129.587041295907, 4184.28867092595, 5672.574556908248, 5676.744546495418, 5680.573956953245, 5687.829365119053, 5725.112607545837, 5968.432998865101, 5984.431479009487, 5984.640921544721, 5987.251593550806, 6031.788887388921, 5604.159376093537, 5621.8594535136945, 5648.068068768607, 5651.943480565224, 5664.858378540487, 5213.635045546851, 5215.113430716788, 5215.817446389434, 5226.817061402832, 5233.816816411408, 6199.411055949678, 6237.09562113491, 6245.219347581552, 6245.531585131133, 6249.843753906098, 5916.319623243382, 5984.13230081627, 5996.130561068708, 6011.879762404815, 6019.72911219005, 6303.5902666328375, 6343.207099112611, 6370.840728981627, 6379.075034120119, 6380.904286435614, 6028.367021462684, 6030.48740857032, 6033.5173186145885, 6039.607425517327, 6039.969800150971, 5828.475437210672, 5975.014122669711, 5976.940230597638, 6108.419700128481, 6130.47890929276, 5837.445442682938, 5906.173135761082, 5917.940820591739, 5931.673757943182, 5949.988501954765, 5914.852128696645, 5929.1995580597795, 5930.940690593211, 5935.020721581063, 5939.4951429129005, 5791.971040144772, 5826.067829147316, 5842.883142337045, 5872.559558033079, 5924.8815023700145, 5990.265818045676, 5991.462091887039, 5999.640021598718, 6027.638341699512, 6035.698215089315, 5836.416358364129, 5841.707914604168, 5841.94158058414, 5846.649201047951, 5855.970720146372], "value": 5563.681179650534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.6438069348736, 627.8807026664934, 649.6849028221312, 660.2473180574145, 676.2662511175374, 35984.640153598455, 39025.80487097564, 47254.52745472549, 48257.75871120639, 48339.0, 48872.26691599629, 49293.0, 49401.25898111531, 49405.258921116125, 49442.75278623611, 8614.56927153643, 9264.444133352004, 9416.62333506659, 9423.952880235589, 9429.764255893624, 10684.946575267151, 11009.504572294227, 11067.391293478855, 11365.602203922865, 11368.204225704205, 11226.382548959804, 11324.490397932113, 11324.886751132506, 11326.830097548544, 11341.546338146465, 11078.667639970814, 11135.276207046598, 11142.05292550126, 11147.0, 11148.331100133977, 10700.0, 10762.192835537366, 10763.3541987481, 10763.623273185402, 10765.3540787553, 45216.0, 45487.317690234675, 45527.08945821065, 45593.088138237385, 45651.086978260246, 3621.818909054563, 4082.6937979651348, 4381.277089280265, 4448.110377924396, 4516.661250406232, 34511.79208727708, 34695.65304346975, 34721.82639086814, 35176.64823351785, 36721.265574688354, 4643.860684179446, 4665.88335291617, 4682.672212945095, 4755.643326750507, 4794.304825800239, 350131.0, 351382.2430887855, 351507.0, 352650.0, 353233.0, 57445.71277143587, 57824.71087644535, 57846.0, 57973.71013144907, 65728.0, 36226.275474490365, 36742.0, 36969.0, 37005.629943700755, 69174.0, 15892.0, 16056.0, 16082.597935051483, 16094.517164485083, 16095.114768687834, 11075.390853502973, 11693.415329233505, 11945.641630751092, 12052.156349055658, 12069.57756478519, 20002.29991950304, 21027.78972210258, 21084.156633734478, 21116.89441552813, 21164.682529762067, 7217.278272172845, 7442.725200911582, 7470.514416562906, 7500.962495187489, 7598.354139898171, 989.1048601016092, 1078.0836289154156, 1078.638656050232, 1111.1888321525332, 1128.4075860173475, 33269.50095748566, 35277.25424065103, 35709.82145089258, 35797.64202357995, 35813.13754791732, 1110.9111271098293, 1149.1036991146918, 1154.549725606997, 1163.6392718257368, 1229.8032314829584, 18412.171452284678, 18441.1701473434, 18518.0, 18630.906845465684, 18656.720149197772, 635247.0, 640465.0, 641373.0, 641735.0, 642429.0, 65524.344756552775, 65741.0, 65947.67026164934, 66115.33884661188, 66351.0, 1342.8321459817523, 1410.189141243789, 1429.4710956945996, 1450.187894778927, 1452.404514149215, 34409.82795086008, 35490.8225458871, 35494.822525887204, 35807.28385432329, 36271.0, 54719.72640136933, 55695.72152139214, 55850.72074639601, 55902.16146757721, 55930.720346399634, 445817.0, 445863.0, 446088.0, 446554.0, 447969.0, 31049.8447507761, 31967.0, 32038.519422209152, 32057.839710801298, 32082.19794505156, 51315.0, 51824.481755181965, 52251.21623175732, 52713.47286527239, 52871.20693189682, 42034.0, 45552.0, 46398.304025440324, 46773.766131169126, 47006.294905577124, 44021.55978440175, 44924.10151797011, 45323.773381134204, 46466.53533464622, 46499.76750116228, 44390.3341449885, 45057.549424506644, 46388.30417543672, 46489.0, 46491.53508465007, 37510.43734343933, 40253.597464026156, 40372.59627403806, 40971.59028409797, 41386.0, 30125.246868828453, 30743.0, 30779.53830692586, 30812.0, 31055.0], "value": 21725.234726407372, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.4267553451771, 426.32120729504527, 433.1899348218831, 437.0712236497443, 441.96022357987795, 19031.904840475796, 20602.89698551505, 20808.583828323426, 21718.0, 21901.780982190172, 19969.500762480944, 20304.796952030476, 20348.186072557117, 20416.081276342564, 20481.897590512064, 5733.713314334287, 5994.430529099728, 5998.2802063752415, 5998.9700051499685, 6005.00917348637, 5745.5690823188215, 5771.163181338703, 5838.065909454488, 5858.912116318259, 5870.765169393219, 5840.474357307841, 5847.5614328925285, 5849.415058494138, 5851.7074146292725, 5851.912221316684, 5660.2075709400715, 5840.591158618899, 5864.560157988168, 5872.031114866042, 5874.942510348135, 5245.895082098375, 5250.343707036621, 5295.841124766263, 5297.337832770903, 5299.152135658314, 24662.87668561646, 24786.38034049145, 24917.62623560648, 24957.5008499829, 25071.0, 2502.699676038869, 2550.553653110717, 2628.342914271432, 2635.64418803461, 2692.8115031947577, 14775.852241477663, 15101.169435681033, 15356.155411452366, 15428.074315541104, 15855.127967961758, 1978.3273686946363, 2066.8863212523306, 2088.7702352741185, 2129.6486079796814, 2147.76374598794, 61935.380646193415, 68206.0, 71375.64312178458, 76685.23314766893, 78523.60738196272, 11633.883661163449, 11902.940485297691, 11948.402579870972, 12021.459034343476, 12049.216800907912, 15345.769813452807, 16008.679826403406, 16947.830521694872, 16978.15109244533, 17516.299348025936, 8143.190599243251, 8237.135100814328, 8306.0, 8393.706220282262, 8400.747977560683, 7718.92281077182, 7738.264864837832, 7764.456488045783, 7925.326347260432, 7927.563983980821, 5040.218766091261, 5122.487751224922, 5143.794248230104, 5264.1946126951, 5288.550473209743, 2024.4230394337544, 2067.555475572759, 2117.8517503774897, 2161.1679503391306, 2164.3290579920276, 917.8623206518942, 949.586929685589, 991.3407583956588, 1046.8063408269495, 1067.236925598201, 20489.48776280605, 20719.689204661943, 20999.790002099784, 21118.0, 21436.78563214379, 1003.6537394598852, 1010.888802231754, 1014.8680671512656, 1028.9691309260734, 1037.5019990404649, 11004.449777511092, 11688.70778230551, 11809.940950295193, 11834.822477662841, 11904.345261010727, 508702.0, 516351.0, 517835.0, 521997.0, 523817.0, 58875.70562147162, 58987.41012589905, 89596.0, 95416.52291738497, 96325.51837240909, 936.4334577580602, 958.2525630008465, 964.9131578158, 976.4190306767462, 1007.969760907174, 18080.819191807914, 18186.909065454587, 18401.90799045996, 18515.3404839112, 18862.905685471484, 43651.34522982221, 43831.561684382745, 44082.0, 44605.0, 44747.0, 57200.42799571951, 58247.708761457616, 58334.4166558346, 58935.11597325958, 61000.38999610124, 8853.46879187225, 8873.556322183736, 9132.634694612141, 9153.954230228806, 9227.538623068685, 7793.844123117619, 10932.562697492329, 12902.354882256037, 13352.0, 14862.628434289229, 11969.190988913362, 12161.209521381083, 12445.0, 12498.0, 12618.369081545703, 11729.94430501259, 11943.925046745835, 12185.878141218474, 12193.69515762113, 12581.496740130659, 12017.158798883995, 12839.679008024874, 12998.415071321624, 13052.934735326262, 13056.869431305566, 9428.15146636806, 9478.431294122374, 9524.714258572254, 9669.323147379617, 9714.805703886024, 11423.143264255048, 12524.749505010031, 12544.686382840502, 12661.620151395473, 12807.743845123232], "value": 9644.570432276087, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.4343306110611, 1545.5981444824347, 1575.0392260720962, 1759.6480703859231, 1867.2437662746584, 8998.865017024742, 9403.059694030602, 9427.34008619396, 9627.13355797978, 9650.613975440974, 9370.765730856714, 9483.0, 9484.52577371132, 9497.952510237457, 9500.524973751302, 2432.2581612608146, 2472.987635061831, 2476.5913624252066, 2480.1443501991785, 2481.950360992779, 2437.756224377557, 2478.8264821462503, 2481.4044629288933, 2487.340854673513, 2488.377905523619, 2411.7708817662383, 2426.2478631624163, 2459.987700061506, 2462.938426539333, 2473.789727873133, 2366.988165059181, 2387.85672859629, 2399.700037495313, 2410.0, 2443.0594221224956, 2385.9522809543887, 2401.0, 2402.447437089462, 2408.3617841272076, 2408.397900524869, 9257.907420925772, 9436.150746432853, 9626.37428567148, 9700.466474343908, 9702.805943881083, 5337.70642614656, 5441.5102640762525, 5441.700706461143, 5465.07093794052, 5479.0, 3443.126482706955, 3494.9825250873837, 3504.456809194579, 3545.503629491873, 3555.1112221944513, 1171.3967306836985, 1191.3149938785145, 1225.4852961756085, 1244.4897591987242, 2111.1977448569446, 13153.473861045546, 13599.0, 13605.659858503617, 13659.521916732967, 13663.18020918748, 4512.729236245836, 4768.3085952536685, 4793.688410253392, 4840.903181936342, 4860.441049279308, 10421.166306695446, 10436.321639093434, 10437.634682786218, 10445.059944605022, 10448.0, 5508.724563771795, 5540.612157149041, 5544.473275038865, 5545.362283337387, 5549.139883318092, 4615.100055489147, 4730.0, 4999.175136102503, 5096.668716533413, 5143.331366922276, 4077.0, 4082.1223436960604, 4148.647364974011, 4184.916301673949, 4185.560516145822, 1237.3565745812127, 1239.8946089582487, 1241.3172754984723, 1248.2697621891296, 1249.6875781054737, 993.7863359377768, 1032.4012072997643, 1046.1264843855465, 1049.5277125293496, 1050.3172937590627, 6110.602810817282, 6374.553781235269, 6383.648899310582, 6439.452646525097, 6512.283648798629, 470.52241974396014, 483.64935421819604, 488.08484092326887, 1071.9678409647722, 1159.1422347462817, 4336.436263285816, 4364.716293440916, 4377.78111094444, 4517.774111294422, 4675.532446755297, 12011.0, 12080.154389192849, 12111.515539378503, 12115.575954841715, 12124.75750485003, 11747.588834390755, 12065.095117866194, 12079.396030198455, 12242.449089790802, 12339.93830030844, 8799.648014079365, 8833.381663283637, 8914.197722205032, 8995.100489950952, 9008.099190080939, 6390.201224846895, 6492.220933488012, 6511.153550038464, 6630.138082049302, 6669.433098186709, 10361.430121343097, 10599.735006624896, 10813.297135686154, 10853.53477280557, 10882.40146791934, 10771.730706732395, 11064.22550421495, 11119.499622517167, 11195.384253866116, 11234.606788762354, 8250.092489826235, 8657.870131948152, 8708.956455217683, 8720.825583488422, 8730.519821409884, 9260.30547708911, 9273.25813934897, 9339.159475646953, 9343.345965782602, 9354.438733676001, 8482.491050536988, 8511.531865747196, 8512.233898948876, 8638.91361086381, 8665.913340866511, 8301.418900677136, 8337.916620833714, 8359.916400836157, 8449.83100338002, 8459.196376344238, 7264.309890560388, 7273.054502914587, 7317.0487836580905, 7322.560646361236, 7341.082364704412, 5525.336959564878, 5637.49262566371, 5651.265335506358, 5689.317281926195, 5757.050086735641, 5531.170324451365, 5665.688387138747, 5734.111212762028, 5786.971065144648, 5852.824415267549], "value": 4931.866516652191, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.49015457123465, 345.99481007784885, 353.3798184186752, 364.9489071529988, 369.9038250054985, 5470.206820011101, 5507.118860982244, 5533.0674742386545, 5538.584606154534, 5553.888922221553, 5427.348718153818, 5433.907945441004, 5473.0, 5474.753636086376, 5480.3971563128025, 3947.605239476058, 4078.614010803676, 4145.150244199932, 4307.138572285539, 4313.309870420733, 4269.658427325807, 4272.807723652428, 4292.248856450125, 4306.655467562588, 4308.922769307673, 4290.97854510727, 4300.354946757996, 4305.094061508485, 4311.3748506466545, 4342.371610646008, 4078.714489985702, 4243.087736136746, 4323.178596066738, 4325.178216138924, 4342.50061242958, 3763.73653844231, 3775.263823554408, 3776.8866933992026, 3784.921297430219, 3794.886153415402, 5296.682199068068, 5308.256844041837, 5323.627346085776, 5342.252084708144, 5358.517733404013, 4831.468538460802, 4898.730569818658, 4917.262410638397, 4959.404871415453, 4963.2058870580895, 5597.132444471114, 5656.547476201894, 5662.631928924648, 5671.291088613923, 5676.8864622707315, 4156.43888075109, 4159.771212583307, 4174.248635245654, 4198.685098617585, 4203.62167404932, 5768.3654797972185, 5912.999999999957, 5989.39461334321, 6011.278646562397, 6018.608790428608, 4191.91616167675, 4199.580041995837, 4200.004997626103, 4216.367544868294, 4237.194932962727, 4284.978575107167, 4285.571442855689, 4291.9785401072795, 4303.870883873489, 4306.20335237982, 4735.95264047362, 4912.0, 4923.556879880756, 4933.914538801458, 4948.480409557017, 5716.828495145152, 5721.456461636138, 5724.570657200727, 5756.971215143897, 5806.912896306559, 1454.5563603101157, 1472.5729538433734, 1473.690524989764, 1474.7050589882067, 1485.5617592810147, 1000.2947921715173, 1025.8102251083574, 1031.4224034540682, 1034.534459493216, 1054.7995880782628, 5524.806631767869, 5545.944540554624, 5555.666620019601, 5606.803761868315, 5625.212470254168, 5908.0, 5916.2013128228, 5933.262044911764, 5942.524598032147, 5978.372270911578, 5436.483534064258, 5506.577106073315, 5541.224228607998, 5561.777528898799, 5604.327480702342, 2673.625692403065, 2752.6696796384563, 2753.9449211016067, 2771.473420050184, 2781.8330900146057, 5121.897562048738, 5274.7626356814035, 5402.37872644643, 5406.135018397038, 5437.265969094201, 5167.560757335594, 5198.870028249324, 5226.869328266747, 5256.316678831804, 5281.181416880389, 5781.88436231273, 5814.796482123105, 5934.3383852521365, 5956.404359564096, 6043.305019922677, 5832.62087964281, 5847.3335099496435, 5848.4151584841175, 5872.383399742966, 5887.852803679943, 5574.526165275997, 5736.282964629421, 5764.510016648632, 5779.2198053263955, 5791.884162316645, 6017.18767966319, 6042.274927008787, 6057.454829065406, 6057.636541807506, 6067.332593414809, 4086.673066154641, 4127.814248358771, 4135.58644135584, 4188.748675079505, 4200.873973780792, 5761.473209599376, 5831.562632802472, 5842.970785146047, 5873.706314684163, 5979.4917432018765, 5698.914516282176, 5716.199732037435, 5761.366249712444, 5763.337216220189, 5764.682942438206, 5570.387257401763, 5714.3999880012, 5718.228039214653, 5725.854829034293, 5734.34055083654, 5684.289463817023, 5692.943070569241, 5705.286839145107, 5707.68607726579, 5708.80019199326, 5723.909089091844, 5738.770449182153, 5754.510866576388, 5757.65454072757, 5770.451807078321, 5385.784568617211, 5530.336359636869, 5584.609077364708, 5622.3815380307315, 5629.155626656035], "value": 4342.810612027862, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.8220209351847, 806.6894245715398, 808.2442915873659, 841.8653015517517, 899.181744612402, 16389.60688341491, 17403.868748531353, 18036.729449058243, 18181.27274909002, 18196.363127290548, 16239.594010149753, 16454.75317870233, 16477.093759843203, 16483.258253378597, 16489.0, 738.4683028219673, 858.549261637639, 871.8038441350693, 879.4020066354876, 881.1056777370974, 857.9227869491745, 871.3682580129419, 872.7730789994615, 872.7861673889906, 873.0309356614154, 928.5032507608431, 929.1359036096428, 929.7210836748948, 929.7722058095742, 930.2465003347277, 916.0152835701581, 917.7705573606598, 921.2629896083107, 922.008840496469, 922.9400088994194, 673.8719643267764, 675.787127054976, 676.0737789228788, 677.0318444624186, 677.5968298862169, 15471.226438678133, 15475.0, 15497.380104795795, 15508.224588770629, 15661.608459788482, 16172.956760809797, 16377.59056023597, 16716.16419179036, 16822.495325140266, 17074.0, 13387.598372048855, 13397.464101435931, 13399.866001339959, 13426.865731342757, 13611.251381174032, 4132.173565286955, 4135.627793498569, 4143.854965076238, 4157.563455837124, 4218.472690913636, 83094.0, 86321.56839215827, 86885.56557217173, 86947.0, 88746.0, 12767.680807979688, 12974.935125324502, 13044.47822087125, 13081.803772943413, 13166.275854827885, 23732.0, 23864.0, 24073.398165045663, 24123.879380602986, 24332.75667243305, 9218.76953076165, 9239.30705197113, 9254.305927055364, 9342.159205671522, 9360.34477586576, 4651.953480465219, 4986.875328116754, 4995.825146119869, 5042.243663450512, 5252.395974462909, 3850.403187505885, 3971.801409929492, 3973.264945985002, 3993.800309984489, 3998.620131087542, 599.1701493431628, 678.2979616097316, 687.6149356360454, 690.2614202803053, 691.4364792693939, 631.5421319543294, 674.8312921769558, 715.7709532949406, 766.5783818899583, 816.6774124220899, 10055.648052318134, 10708.19688523364, 10847.240693151562, 10875.238733288752, 11165.72085697864, 701.0885848397063, 738.6860584251779, 739.4306384084225, 772.5441989226383, 806.7983004248938, 4769.59458446029, 4936.827211047668, 4976.42771081323, 4990.2764099205415, 5127.256547800548, 128279.0, 128465.35767321104, 130530.0, 132157.07057576577, 134225.328873355, 111862.0, 113651.0, 115524.0, 117136.41431792786, 117216.41391792987, 91954.54022729844, 97558.0, 98319.50840245753, 99253.50373247942, 99621.50189249008, 9723.805523889625, 9797.31418800677, 9897.455639939872, 9944.30389872702, 9977.750556236153, 56696.433035670765, 56742.716286418305, 57374.0, 58038.70980645239, 58320.70839645775, 80479.19520804717, 83382.0, 84985.0, 85380.0, 88069.0, 10680.092192163753, 10942.288751231144, 11030.06244469229, 13291.468341266242, 13835.723285534434, 14047.719045619235, 14393.856061439252, 14534.273286335852, 14613.19627420502, 15260.771088433905, 12529.18560293578, 12615.116941813985, 12765.170263932814, 13070.607881763563, 13611.25138117413, 14450.85549144495, 14811.481598144013, 14823.431575404224, 14912.105273683614, 15076.170810605521, 13763.587092387244, 14638.634034149232, 14702.77945830792, 14811.555653330417, 14886.925565372103, 11016.986958655358, 11072.501737421997, 11088.611898583511, 11320.207585469267, 11745.706357341134, 11853.0, 12560.937195313965, 12747.553835615709, 12836.486540538273, 12986.610401687587], "value": 8167.0077972796025, "unit": "score"}], "name": "dromaeo_css", "value": 7672.140963412056, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 120.0, 111.0, 113.0, 110.0, 110.0, 111.0, 111.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 147.0, 173.0, 115.0, 143.0, 144.0, 121.0, 176.0, 115.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 161.0, 160.0, 161.0, 162.0, 162.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 186.0, 75.0, 75.0, 76.0, 75.0, 179.0, 74.0], "value": 96.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 169.0, 83.0, 83.0, 83.0, 144.0, 83.0, 84.0, 147.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 147.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 49.0, 49.0, 50.0, 51.0, 50.0, 52.0, 50.0, 51.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 72.0, 73.0, 77.0, 81.0, 74.0, 78.0, 74.0, 79.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 123.0, 126.0, 121.0, 126.0, 125.0, 117.0, 129.0, 170.0, 126.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 153.0, 149.0, 150.0, 149.0, 149.0, 154.0, 148.0, 151.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 64.0, 61.0, 62.0, 60.0, 60.0, 61.0, 66.0, 59.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1499.0999999999997, "alertThreshold": 2.0}]} 14:20:15 INFO - Return code: 0 14:20:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:20:15 INFO - # TBPL SUCCESS # 14:20:15 INFO - Running post-action listener: _resource_record_post_action 14:20:15 INFO - [mozharness: 2016-10-27 21:20:15.245654Z] Finished run-tests step (success) 14:20:15 INFO - Running post-run listener: _resource_record_post_run 14:20:15 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290131968; Read time: 12; Write time: 192088 14:20:15 INFO - TinderboxPrint: CPU usage
12.9% 14:20:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:20:15 INFO - TinderboxPrint: I/O write bytes / time
290,131,968 / 192,088 14:20:15 INFO - TinderboxPrint: CPU idle
8,243.7 (87.1%) 14:20:15 INFO - TinderboxPrint: CPU user
1,180.0 (12.5%) 14:20:15 INFO - TinderboxPrint: Swap in / out
0 / 0 14:20:15 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71319552; Read time: 0; Write time: 65616 14:20:15 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 218812416; Read time: 0; Write time: 126472 14:20:15 INFO - Running post-run listener: _upload_blobber_files 14:20:15 INFO - Blob upload gear active. 14:20:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:20:15 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 14:20:15 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'] 14:20:15 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 14:20:16 INFO - (blobuploader) - INFO - Open directory for files ... 14:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:16 INFO - (blobuploader) - INFO - Done attempting. 14:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:20:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:17 INFO - (blobuploader) - INFO - Done attempting. 14:20:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:20:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:20:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:20 INFO - (blobuploader) - INFO - Done attempting. 14:20:20 INFO - (blobuploader) - INFO - Iteration through files over. 14:20:20 INFO - Return code: 0 14:20:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:20:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:20:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c1788d15b06375668c099363424246fd4ac63622f0fad455a36b294866762d415761a3b024d75b9978390e97f72152e6bfcbc473e0e11f59c0b93a89d807f514", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa979fcd6ce8b19cfc97b4ed63acf5b764b2c8944466400ef6b34a317582c0e9c8f7a21b31e84006a156d541bd6352a40bb098a30224f9379a360bba1f62becc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08309099521bbdf15a5c117d08444b9c254b9ddedee0474b2a5b84f28ef1a4418384e1ec064e8b7ee2a68844aa3e0ce36d389b5838520dc59b0ccce68026474b"} 14:20:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:20:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:20:20 INFO - Contents: 14:20:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c1788d15b06375668c099363424246fd4ac63622f0fad455a36b294866762d415761a3b024d75b9978390e97f72152e6bfcbc473e0e11f59c0b93a89d807f514", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa979fcd6ce8b19cfc97b4ed63acf5b764b2c8944466400ef6b34a317582c0e9c8f7a21b31e84006a156d541bd6352a40bb098a30224f9379a360bba1f62becc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08309099521bbdf15a5c117d08444b9c254b9ddedee0474b2a5b84f28ef1a4418384e1ec064e8b7ee2a68844aa3e0ce36d389b5838520dc59b0ccce68026474b"} 14:20:20 INFO - Running post-run listener: copy_logs_to_upload_dir 14:20:20 INFO - Copying logs to upload dir... 14:20:20 INFO - mkdir: /builds/slave/test/build/upload/logs 14:20:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.354554 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-27 14:20:19.898978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 14:20:19.902775) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 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/c1788d15b06375668c099363424246fd4ac63622f0fad455a36b294866762d415761a3b024d75b9978390e97f72152e6bfcbc473e0e11f59c0b93a89d807f514", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa979fcd6ce8b19cfc97b4ed63acf5b764b2c8944466400ef6b34a317582c0e9c8f7a21b31e84006a156d541bd6352a40bb098a30224f9379a360bba1f62becc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08309099521bbdf15a5c117d08444b9c254b9ddedee0474b2a5b84f28ef1a4418384e1ec064e8b7ee2a68844aa3e0ce36d389b5838520dc59b0ccce68026474b"} build_url:https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/NyUhgzcFQOORyptSJ-OXQg/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/c1788d15b06375668c099363424246fd4ac63622f0fad455a36b294866762d415761a3b024d75b9978390e97f72152e6bfcbc473e0e11f59c0b93a89d807f514", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa979fcd6ce8b19cfc97b4ed63acf5b764b2c8944466400ef6b34a317582c0e9c8f7a21b31e84006a156d541bd6352a40bb098a30224f9379a360bba1f62becc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/08309099521bbdf15a5c117d08444b9c254b9ddedee0474b2a5b84f28ef1a4418384e1ec064e8b7ee2a68844aa3e0ce36d389b5838520dc59b0ccce68026474b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 14:20:19.930429) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 14:20:19.930841) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1477601545.420056-1182359426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004376 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 14:20:20.019466) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 14:20:20.020189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 14:20:20.020812) ========= ========= Total master_lag: 0.17 =========