builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1461193284.28 results: success (0) buildid: 20160420141303 builduid: 9f1acf046f0e4dccbe6dfecaac9bc874 revision: c1a9497a212d54fda334e9174aac33926196df8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 16:01:24.285321) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 16:01:24.285737) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2016-04-20 16:01:24.286011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 16 secs) (at 2016-04-20 16:01:40.988893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 16:01:40.989170) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 16:01:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.34M=0.001s 2016-04-20 16:01:42 (8.34 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.561475 ========= master_lag: 1.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 16:01:43.047785) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 16:01:43.048074) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020696 ========= master_lag: 3.70 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 16:01:46.771376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 16:01:46.771725) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c1a9497a212d54fda334e9174aac33926196df8e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c1a9497a212d54fda334e9174aac33926196df8e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 16:01:47,326 truncating revision to first 12 chars 2016-04-20 16:01:47,326 Setting DEBUG logging. 2016-04-20 16:01:47,326 attempt 1/10 2016-04-20 16:01:47,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c1a9497a212d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 16:01:48,602 unpacking tar archive at: fx-team-c1a9497a212d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526170 ========= master_lag: 6.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 16:01:54.541738) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 16:01:54.542042) ========= script_repo_revision: c1a9497a212d54fda334e9174aac33926196df8e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 16:01:54.542424) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-04-20 16:01:54.542727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-04-20 16:01:57.838082) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-20 16:01:57.838365) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:01:58 INFO - MultiFileLogger online at 20160420 16:01:58 in /builds/slave/test 16:01:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:01:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:58 INFO - {'append_to_log': False, 16:01:58 INFO - 'base_work_dir': '/builds/slave/test', 16:01:58 INFO - 'blob_upload_branch': 'Fx-Team', 16:01:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:58 INFO - 'branch': 'Fx-Team', 16:01:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:58 INFO - 'config_files': ('talos/mac_config.py',), 16:01:58 INFO - 'default_actions': ('clobber', 16:01:58 INFO - 'read-buildbot-config', 16:01:58 INFO - 'download-and-extract', 16:01:58 INFO - 'populate-webroot', 16:01:58 INFO - 'create-virtualenv', 16:01:58 INFO - 'install', 16:01:58 INFO - 'run-tests'), 16:01:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:58 INFO - 'download_minidump_stackwalk': True, 16:01:58 INFO - 'download_symbols': 'ondemand', 16:01:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:58 INFO - 'installer_path': 'installer.exe', 16:01:58 INFO - 'log_level': 'info', 16:01:58 INFO - 'log_name': 'talos', 16:01:58 INFO - 'log_to_console': True, 16:01:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:01:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:01:58 INFO - 'opt_config_files': (), 16:01:58 INFO - 'pip_index': False, 16:01:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:01:58 INFO - 'cmd': ('bash', 16:01:58 INFO - '-c', 16:01:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:01:58 INFO - 'enabled': True, 16:01:58 INFO - 'halt_on_failure': False, 16:01:58 INFO - 'name': 'check_screen_resolution'},), 16:01:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:01:58 INFO - 'cmd': ('bash', 16:01:58 INFO - '-c', 16:01:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:01:58 INFO - 'enabled': True, 16:01:58 INFO - 'halt_on_failure': False, 16:01:58 INFO - 'name': 'check_screen_resolution'},), 16:01:58 INFO - 'run_cmd_checks_enabled': True, 16:01:58 INFO - 'sps_profile': False, 16:01:58 INFO - 'sps_profile_interval': 0, 16:01:58 INFO - 'suite': 'dromaeojs', 16:01:58 INFO - 'system_bits': '32', 16:01:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:58 INFO - 'title': 't-yosemite-r7-0116', 16:01:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:58 INFO - 'use_talos_json': True, 16:01:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:58 INFO - 'work_dir': 'build'} 16:01:58 INFO - ##### 16:01:58 INFO - ##### Running clobber step. 16:01:58 INFO - ##### 16:01:58 INFO - Running pre-action listener: _resource_record_pre_action 16:01:58 INFO - Running main action method: clobber 16:01:58 INFO - rmtree: /builds/slave/test/build 16:01:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:59 INFO - Running post-action listener: _resource_record_post_action 16:01:59 INFO - ##### 16:01:59 INFO - ##### Running read-buildbot-config step. 16:01:59 INFO - ##### 16:01:59 INFO - Running pre-action listener: _resource_record_pre_action 16:01:59 INFO - Running main action method: read_buildbot_config 16:01:59 INFO - Using buildbot properties: 16:01:59 INFO - { 16:01:59 INFO - "project": "", 16:01:59 INFO - "product": "firefox", 16:01:59 INFO - "script_repo_revision": "production", 16:01:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:01:59 INFO - "repository": "", 16:01:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:01:59 INFO - "buildid": "20160420141303", 16:01:59 INFO - "pgo_build": "False", 16:01:59 INFO - "basedir": "/builds/slave/test", 16:01:59 INFO - "buildnumber": 744, 16:01:59 INFO - "slavename": "t-yosemite-r7-0116", 16:01:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:01:59 INFO - "platform": "yosemite_r7", 16:01:59 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:01:59 INFO - "branch": "fx-team", 16:01:59 INFO - "repo_path": "integration/fx-team", 16:01:59 INFO - "revision": "c1a9497a212d54fda334e9174aac33926196df8e", 16:01:59 INFO - "stage_platform": "macosx64", 16:01:59 INFO - "builduid": "9f1acf046f0e4dccbe6dfecaac9bc874", 16:01:59 INFO - "slavebuilddir": "test" 16:01:59 INFO - } 16:01:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 16:01:59 INFO - Running post-action listener: _resource_record_post_action 16:01:59 INFO - ##### 16:01:59 INFO - ##### Running download-and-extract step. 16:01:59 INFO - ##### 16:01:59 INFO - Running pre-action listener: _resource_record_pre_action 16:01:59 INFO - Running main action method: download_and_extract 16:01:59 INFO - mkdir: /builds/slave/test/build/tests 16:01: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')]} 16:01:59 INFO - https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:01:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:01:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:01:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:01:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 16:02:02 INFO - Downloaded 1199 bytes. 16:02:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:02:02 INFO - Using the following test package requirements: 16:02:02 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:02:02 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 16:02:02 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 16:02:02 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'jsshell-mac.zip'], 16:02:02 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 16:02:02 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:02:02 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 16:02:02 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 16:02:02 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 16:02:02 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:02:02 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 16:02:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:02:02 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')]} 16:02:02 INFO - https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:02:03 INFO - Downloaded 18096161 bytes. 16:02:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:02:06 INFO - Return code: 0 16:02:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:02:06 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')]} 16:02:06 INFO - https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:02:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:02:07 INFO - Downloaded 18096161 bytes. 16:02:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:02:11 INFO - Return code: 0 16:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:11 INFO - https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:02:12 INFO - Downloaded 10845952 bytes. 16:02:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:02:12 INFO - Return code: 0 16:02: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')]} 16:02:12 INFO - https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:02:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:02:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:02:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 16:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 16:02:15 INFO - Downloaded 110019564 bytes. 16:02:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:02:15 INFO - mkdir: /builds/slave/test/properties 16:02:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:02:15 INFO - Writing to file /builds/slave/test/properties/build_url 16:02:15 INFO - Contents: 16:02:15 INFO - build_url:https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:02:17 INFO - Running post-action listener: _resource_record_post_action 16:02:17 INFO - Running post-action listener: set_extra_try_arguments 16:02:17 INFO - ##### 16:02:17 INFO - ##### Running populate-webroot step. 16:02:17 INFO - ##### 16:02:17 INFO - Running pre-action listener: _resource_record_pre_action 16:02:17 INFO - Running main action method: populate_webroot 16:02:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:02:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:02:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:02:17 INFO - '--tppagecycles', 16:02:17 INFO - '7'], 16:02:17 INFO - 'tests': ['tsvgm']}}, 16:02:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:02:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:02:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:02:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:02:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['damp', 'tps']}, 16:02:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'tests': ['damp', 'tps']}, 16:02:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['dromaeo_dom']}, 16:02:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:02:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'other-e10s': {'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['a11yr', 16:02:17 INFO - 'ts_paint', 16:02:17 INFO - 'tpaint', 16:02:17 INFO - 'sessionrestore', 16:02:17 INFO - 'sessionrestore_no_auto_restore', 16:02:17 INFO - 'tabpaint']}, 16:02:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['tsvgx', 16:02:17 INFO - 'tsvgr_opacity', 16:02:17 INFO - 'tart', 16:02:17 INFO - 'tscrollx', 16:02:17 INFO - 'cart']}, 16:02:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:02:17 INFO - 'tsvgr_opacity', 16:02:17 INFO - 'tart', 16:02:17 INFO - 'tscrollx', 16:02:17 INFO - 'cart']}, 16:02:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'talos_options': ['--disable-e10s'], 16:02:17 INFO - 'tests': ['tp5o']}, 16:02:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'tests': ['tp5o']}, 16:02:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'talos_options': ['--disable-e10s', 16:02:17 INFO - '--xperf_path', 16:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:17 INFO - 'tests': ['tp5n']}, 16:02:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:02:17 INFO - 'talos_options': ['--xperf_path', 16:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:17 INFO - 'tests': ['tp5n']}}, 16:02:17 INFO - 'talos.zip': {'path': '', 16:02:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:02:17 INFO - Running post-action listener: _resource_record_post_action 16:02:17 INFO - ##### 16:02:17 INFO - ##### Running create-virtualenv step. 16:02:17 INFO - ##### 16:02:17 INFO - Running pre-action listener: _resource_record_pre_action 16:02:17 INFO - Running main action method: create_virtualenv 16:02:17 INFO - Creating virtualenv /builds/slave/test/build/venv 16:02:17 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 16:02:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:02:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:02:17 INFO - Using real prefix '/tools/python27' 16:02:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:02:18 INFO - Installing distribute.............................................................................................................................................................................................done. 16:02:21 INFO - Installing pip.................done. 16:02:21 INFO - Return code: 0 16:02:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:21 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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:21 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 16:02:21 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 16:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:21 INFO - 'HOME': '/Users/cltbld', 16:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:21 INFO - 'LOGNAME': 'cltbld', 16:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:21 INFO - 'MOZ_NO_REMOTE': '1', 16:02:21 INFO - 'NO_EM_RESTART': '1', 16:02:21 INFO - 'PAGER': '/bin/cat', 16:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:21 INFO - 'PWD': '/builds/slave/test', 16:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:21 INFO - 'SHELL': '/bin/bash', 16:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:21 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:21 INFO - 'USER': 'cltbld', 16:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:21 INFO - 'XPC_FLAGS': '0x0', 16:02:21 INFO - 'XPC_SERVICE_NAME': '0', 16:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:21 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 16:02:21 INFO - Cleaning up... 16:02:21 INFO - Return code: 0 16:02:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:21 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:21 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>=0.7.1'] in /builds/slave/test/build 16:02:21 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>=0.7.1 16:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:21 INFO - 'HOME': '/Users/cltbld', 16:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:21 INFO - 'LOGNAME': 'cltbld', 16:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:21 INFO - 'MOZ_NO_REMOTE': '1', 16:02:21 INFO - 'NO_EM_RESTART': '1', 16:02:21 INFO - 'PAGER': '/bin/cat', 16:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:21 INFO - 'PWD': '/builds/slave/test', 16:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:21 INFO - 'SHELL': '/bin/bash', 16:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:21 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:21 INFO - 'USER': 'cltbld', 16:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:21 INFO - 'XPC_FLAGS': '0x0', 16:02:21 INFO - 'XPC_SERVICE_NAME': '0', 16:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:21 INFO - Downloading/unpacking psutil>=0.7.1 16:02:21 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 16:02:21 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 16:02:21 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 16:02:21 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 16:02:21 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 16:02:21 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 16:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:02:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:24 INFO - Installing collected packages: psutil 16:02:24 INFO - Running setup.py install for psutil 16:02:24 INFO - building 'psutil._psutil_osx' extension 16:02:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:02:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:02:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:02:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:02:25 INFO - building 'psutil._psutil_posix' extension 16:02:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:02:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:02:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:02:25 INFO - ^ 16:02:25 INFO - 1 warning generated. 16:02:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:02:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:25 INFO - Successfully installed psutil 16:02:25 INFO - Cleaning up... 16:02:25 INFO - Return code: 0 16:02:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:25 INFO - 'HOME': '/Users/cltbld', 16:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:25 INFO - 'LOGNAME': 'cltbld', 16:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:25 INFO - 'MOZ_NO_REMOTE': '1', 16:02:25 INFO - 'NO_EM_RESTART': '1', 16:02:25 INFO - 'PAGER': '/bin/cat', 16:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:25 INFO - 'PWD': '/builds/slave/test', 16:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:25 INFO - 'SHELL': '/bin/bash', 16:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:25 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:25 INFO - 'USER': 'cltbld', 16:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:25 INFO - 'XPC_FLAGS': '0x0', 16:02:25 INFO - 'XPC_SERVICE_NAME': '0', 16:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:02: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 16:02: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 16:02: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 16:02: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 16:02: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 16:02: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 16:02:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:02:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:02:28 INFO - Installing collected packages: mozsystemmonitor 16:02:28 INFO - Running setup.py install for mozsystemmonitor 16:02:28 INFO - Successfully installed mozsystemmonitor 16:02:28 INFO - Cleaning up... 16:02:28 INFO - Return code: 0 16:02:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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', '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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:02: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 blobuploader==1.2.4 16:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:28 INFO - 'HOME': '/Users/cltbld', 16:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:28 INFO - 'LOGNAME': 'cltbld', 16:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:28 INFO - 'MOZ_NO_REMOTE': '1', 16:02:28 INFO - 'NO_EM_RESTART': '1', 16:02:28 INFO - 'PAGER': '/bin/cat', 16:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:28 INFO - 'PWD': '/builds/slave/test', 16:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:28 INFO - 'SHELL': '/bin/bash', 16:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:28 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:28 INFO - 'USER': 'cltbld', 16:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:28 INFO - 'XPC_FLAGS': '0x0', 16:02:28 INFO - 'XPC_SERVICE_NAME': '0', 16:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:28 INFO - Downloading/unpacking blobuploader==1.2.4 16:02:28 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 16:02:28 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 16:02:28 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 16:02:28 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 16:02:28 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 16:02:28 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 16:02:31 INFO - Downloading blobuploader-1.2.4.tar.gz 16:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:02:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:02:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:32 INFO - Downloading docopt-0.6.1.tar.gz 16:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 16:02:32 INFO - Running setup.py install for blobuploader 16:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:02:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:02:32 INFO - Running setup.py install for requests 16:02:32 INFO - Running setup.py install for docopt 16:02:33 INFO - Successfully installed blobuploader requests docopt 16:02:33 INFO - Cleaning up... 16:02:33 INFO - Return code: 0 16:02:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:33 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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:33 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 16:02:33 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 16:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:33 INFO - 'HOME': '/Users/cltbld', 16:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:33 INFO - 'LOGNAME': 'cltbld', 16:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:33 INFO - 'MOZ_NO_REMOTE': '1', 16:02:33 INFO - 'NO_EM_RESTART': '1', 16:02:33 INFO - 'PAGER': '/bin/cat', 16:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:33 INFO - 'PWD': '/builds/slave/test', 16:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:33 INFO - 'SHELL': '/bin/bash', 16:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:33 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:33 INFO - 'USER': 'cltbld', 16:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:33 INFO - 'XPC_FLAGS': '0x0', 16:02:33 INFO - 'XPC_SERVICE_NAME': '0', 16:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:33 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-uSVW3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:33 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-CiFzZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:33 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-KXuK8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:33 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-4Cp4oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:33 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-46g5iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-GZD4Fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Fonxor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-yDKG0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-5Wg4FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hrgYC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-68jxeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-KV7Ruu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-byzKQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-OubV7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:34 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-URc0Jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:35 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EyNtrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:35 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-inibUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:02:35 INFO - Running setup.py install for manifestparser 16:02:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:02:35 INFO - Running setup.py install for mozcrash 16:02:35 INFO - Running setup.py install for mozdebug 16:02:35 INFO - Running setup.py install for mozdevice 16:02:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:02:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:02:35 INFO - Running setup.py install for mozfile 16:02:36 INFO - Running setup.py install for mozhttpd 16:02:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozinfo 16:02:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozInstall 16:02:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozleak 16:02:36 INFO - Running setup.py install for mozlog 16:02:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for moznetwork 16:02:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozprocess 16:02:37 INFO - Running setup.py install for mozprofile 16:02:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for mozrunner 16:02:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for mozscreenshot 16:02:37 INFO - Running setup.py install for moztest 16:02:37 INFO - Running setup.py install for mozversion 16:02:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:37 INFO - Cleaning up... 16:02:37 INFO - Return code: 0 16:02:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:37 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')]} 16:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:37 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')]} 16:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:37 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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:37 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 16:02:37 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 16:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:37 INFO - 'HOME': '/Users/cltbld', 16:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:37 INFO - 'LOGNAME': 'cltbld', 16:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:37 INFO - 'MOZ_NO_REMOTE': '1', 16:02:37 INFO - 'NO_EM_RESTART': '1', 16:02:37 INFO - 'PAGER': '/bin/cat', 16:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:37 INFO - 'PWD': '/builds/slave/test', 16:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:37 INFO - 'SHELL': '/bin/bash', 16:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:37 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:37 INFO - 'USER': 'cltbld', 16:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:37 INFO - 'XPC_FLAGS': '0x0', 16:02:37 INFO - 'XPC_SERVICE_NAME': '0', 16:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EgmQms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ONXpYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-_ISqmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-u1tyyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-DVLPPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-rp52hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-af25iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:38 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)) 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:38 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-f4DeQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Be5CBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EQsjHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8pTISB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1IQvKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-mIcXI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nF39XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-uTm7rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-CJFSrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:39 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)) 16:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BM6MRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:40 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)) 16:02:40 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)) 16:02:40 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)) 16:02:40 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)) 16:02:40 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)) 16:02:40 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)) 16:02:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:42 INFO - Downloading blessings-1.6.tar.gz 16:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:42 INFO - Installing collected packages: blessings 16:02:42 INFO - Running setup.py install for blessings 16:02:42 INFO - Successfully installed blessings 16:02:42 INFO - Cleaning up... 16:02:42 INFO - Return code: 0 16:02:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:43 INFO - Reading from file tmpfile_stdout 16:02:43 INFO - Current package versions: 16:02:43 INFO - blessings == 1.6 16:02:43 INFO - blobuploader == 1.2.4 16:02:43 INFO - docopt == 0.6.1 16:02:43 INFO - manifestparser == 1.1 16:02:43 INFO - mozInstall == 1.12 16:02:43 INFO - mozcrash == 0.17 16:02:43 INFO - mozdebug == 0.1 16:02:43 INFO - mozdevice == 0.48 16:02:43 INFO - mozfile == 1.2 16:02:43 INFO - mozhttpd == 0.7 16:02:43 INFO - mozinfo == 0.9 16:02:43 INFO - mozleak == 0.1 16:02:43 INFO - mozlog == 3.1 16:02:43 INFO - moznetwork == 0.27 16:02:43 INFO - mozprocess == 0.22 16:02:43 INFO - mozprofile == 0.28 16:02:43 INFO - mozrunner == 6.11 16:02:43 INFO - mozscreenshot == 0.1 16:02:43 INFO - mozsystemmonitor == 0.0 16:02:43 INFO - moztest == 0.7 16:02:43 INFO - mozversion == 1.4 16:02:43 INFO - psutil == 3.1.1 16:02:43 INFO - requests == 1.2.3 16:02:43 INFO - wsgiref == 0.1.2 16:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:43 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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:43 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 16:02:43 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 16:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:43 INFO - 'HOME': '/Users/cltbld', 16:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:43 INFO - 'LOGNAME': 'cltbld', 16:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:43 INFO - 'MOZ_NO_REMOTE': '1', 16:02:43 INFO - 'NO_EM_RESTART': '1', 16:02:43 INFO - 'PAGER': '/bin/cat', 16:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:43 INFO - 'PWD': '/builds/slave/test', 16:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:43 INFO - 'SHELL': '/bin/bash', 16:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:43 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:43 INFO - 'USER': 'cltbld', 16:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:43 INFO - 'XPC_FLAGS': '0x0', 16:02:43 INFO - 'XPC_SERVICE_NAME': '0', 16:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 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)) 16:02:43 INFO - Cleaning up... 16:02:43 INFO - Return code: 0 16:02:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:43 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 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:43 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 16:02:43 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 16:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:43 INFO - 'HOME': '/Users/cltbld', 16:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:43 INFO - 'LOGNAME': 'cltbld', 16:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:43 INFO - 'MOZ_NO_REMOTE': '1', 16:02:43 INFO - 'NO_EM_RESTART': '1', 16:02:43 INFO - 'PAGER': '/bin/cat', 16:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:43 INFO - 'PWD': '/builds/slave/test', 16:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:43 INFO - 'SHELL': '/bin/bash', 16:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:43 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:43 INFO - 'USER': 'cltbld', 16:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:43 INFO - 'XPC_FLAGS': '0x0', 16:02:43 INFO - 'XPC_SERVICE_NAME': '0', 16:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:43 INFO - Downloading/unpacking jsonschema 16:02:43 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 16:02:43 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 16:02:43 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 16:02:43 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 16:02:43 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 16:02:43 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 16:02:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:46 INFO - Downloading/unpacking functools32 (from jsonschema) 16:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:46 INFO - warning: no files found matching '*.txt' 16:02:46 INFO - no previously-included directories found matching 'build' 16:02:46 INFO - no previously-included directories found matching 'dist' 16:02:46 INFO - no previously-included directories found matching '.git*' 16:02:46 INFO - Installing collected packages: jsonschema, functools32 16:02:46 INFO - Running setup.py install for functools32 16:02:46 INFO - warning: no files found matching '*.txt' 16:02:46 INFO - no previously-included directories found matching 'build' 16:02:46 INFO - no previously-included directories found matching 'dist' 16:02:46 INFO - no previously-included directories found matching '.git*' 16:02:46 INFO - Successfully installed jsonschema functools32 16:02:46 INFO - Cleaning up... 16:02:46 INFO - Return code: 0 16:02:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:46 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10110da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10111d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe49ad999a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:02:46 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', 'flake8'] in /builds/slave/test/build 16:02:46 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 flake8 16:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:46 INFO - 'HOME': '/Users/cltbld', 16:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:46 INFO - 'LOGNAME': 'cltbld', 16:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:46 INFO - 'MOZ_NO_REMOTE': '1', 16:02:46 INFO - 'NO_EM_RESTART': '1', 16:02:46 INFO - 'PAGER': '/bin/cat', 16:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:46 INFO - 'PWD': '/builds/slave/test', 16:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:46 INFO - 'SHELL': '/bin/bash', 16:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:02:46 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:46 INFO - 'USER': 'cltbld', 16:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:46 INFO - 'XPC_FLAGS': '0x0', 16:02:46 INFO - 'XPC_SERVICE_NAME': '0', 16:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:47 INFO - Downloading/unpacking flake8 16:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:02:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:02:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:49 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 16:02:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:02:50 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 16:02:50 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 16:02:50 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 16:02:50 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 16:02:50 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 16:02:50 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 16:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:02:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:50 INFO - no previously-included directories found matching 'docs/_build' 16:02:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:02:50 INFO - Running setup.py install for pep8 16:02:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:51 INFO - no previously-included directories found matching 'docs/_build' 16:02:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:02:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:02:51 INFO - Cleaning up... 16:02:51 INFO - Return code: 0 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - Running post-action listener: _start_resource_monitoring 16:02:51 INFO - Starting resource monitoring. 16:02:51 INFO - ##### 16:02:51 INFO - ##### Running install step. 16:02:51 INFO - ##### 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: install 16:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:51 INFO - Reading from file tmpfile_stdout 16:02:51 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:51 INFO - Reading from file tmpfile_stdout 16:02:51 INFO - Output received: 16:02:51 INFO - Usage: mozinstall [options] installer 16:02:51 INFO - Options: 16:02:51 INFO - -h, --help show this help message and exit 16:02:51 INFO - -d DEST, --destination=DEST 16:02:51 INFO - Directory to install application into. [default: 16:02:51 INFO - "/builds/slave/test"] 16:02:51 INFO - --app=APP Application being installed. [default: firefox] 16:02:51 INFO - mkdir: /builds/slave/test/build/application 16:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:03:25 INFO - Reading from file tmpfile_stdout 16:03:25 INFO - Output received: 16:03:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:03:25 INFO - Running post-action listener: _resource_record_post_action 16:03:25 INFO - ##### 16:03:25 INFO - ##### Running run-tests step. 16:03:25 INFO - ##### 16:03:25 INFO - Running pre-action listener: _resource_record_pre_action 16:03:25 INFO - Running main action method: run_tests 16:03:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:03:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:03:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:03:25 INFO - 2016-04-20 16:03:25.161 screenresolution[3052:47955] starting screenresolution argv=screenresolution get 16:03:25 INFO - 2016-04-20 16:03:25.164 screenresolution[3052:47955] Display 0: 1600x1200x32@60 16:03:25 INFO - 2016-04-20 16:03:25.170 screenresolution[3053:47957] starting screenresolution argv=screenresolution list 16:03:25 INFO - Available Modes on Display 0 16:03:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:03:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:03:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:03:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:03:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:03:25 INFO - Intel Iris: 16:03:25 INFO - Chipset Model: Intel Iris 16:03:25 INFO - Type: GPU 16:03:25 INFO - Bus: Built-In 16:03:25 INFO - VRAM (Dynamic, Max): 1536 MB 16:03:25 INFO - Vendor: Intel (0x8086) 16:03:25 INFO - Device ID: 0x0a2e 16:03:25 INFO - Revision ID: 0x0009 16:03:25 INFO - Displays: 16:03:25 INFO - Display: 16:03:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:03:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:03:25 INFO - Display Serial Number: 1600x1200 60 16:03:25 INFO - Main Display: Yes 16:03:25 INFO - Mirror: Off 16:03:25 INFO - Online: Yes 16:03:25 INFO - Rotation: Supported 16:03:25 INFO - Return code: 0 16:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:25 INFO - Python 2.7.3 16:03:25 INFO - Return code: 0 16:03:25 INFO - grabbing minidump binary from tooltool 16:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:03:25 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100face00>, '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 0x100dd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fa7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:25 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:03:25 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:03:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:25 INFO - Return code: 0 16:03:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:03:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:03:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:03:26 INFO - Return code: 0 16:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 16:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 16:03:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rvbhMF4dJY/Render', 16:03:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:03:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:03:26 INFO - 'HOME': '/Users/cltbld', 16:03:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:03:26 INFO - 'LOGNAME': 'cltbld', 16:03:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:26 INFO - 'MOZ_NO_REMOTE': '1', 16:03:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:26 INFO - 'NO_EM_RESTART': '1', 16:03:26 INFO - 'PAGER': '/bin/cat', 16:03:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:26 INFO - 'PWD': '/builds/slave/test', 16:03:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:03:26 INFO - 'SHELL': '/bin/bash', 16:03:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners', 16:03:26 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 16:03:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:03:26 INFO - 'USER': 'cltbld', 16:03:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:03:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:26 INFO - 'XPC_FLAGS': '0x0', 16:03:26 INFO - 'XPC_SERVICE_NAME': '0', 16:03:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:03:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 16:03:26 INFO - mozversion application_buildid: 20160420141303 16:03:26 INFO - mozversion application_changeset: c1a9497a212d54fda334e9174aac33926196df8e 16:03:26 INFO - mozversion application_display_name: Nightly 16:03:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:26 INFO - mozversion application_name: Firefox 16:03:26 INFO - mozversion application_remotingname: firefox 16:03:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:03:26 INFO - mozversion application_vendor: Mozilla 16:03:26 INFO - mozversion application_version: 48.0a1 16:03:26 INFO - mozversion platform_buildid: 20160420141303 16:03:26 INFO - mozversion platform_changeset: c1a9497a212d54fda334e9174aac33926196df8e 16:03:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:03:26 INFO - mozversion platform_version: 48.0a1 16:03:26 INFO - using testdate: 1461193406 16:03:26 INFO - actual date: 1461193406 16:03:26 INFO - starting webserver on 'localhost:53716' 16:03:26 INFO - SUITE-START | Running 2 tests 16:03:26 INFO - TEST-START | dromaeo_css 16:03:26 INFO - operating with platform_type : mac_ 16:03:26 INFO - Initialising browser for dromaeo_css test... 16:03:26 INFO - TEST-INFO | started process 3071 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpIgIrLz/profile http://localhost:53716/getInfo.html) 16:03:29 INFO - PROCESS | 3071 | __metrics Screen width/height:1600/1200 16:03:29 INFO - PROCESS | 3071 | colorDepth:24 16:03:29 INFO - PROCESS | 3071 | Browser inner width/height: 1024/689 16:03:29 INFO - PROCESS | 3071 | __metrics 16:03:29 INFO - PROCESS | 3071 | 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" 16:03:29 ERROR - PROCESS | 3071 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:03:29 INFO - TEST-INFO | 3071: exit 0 16:03:29 INFO - Browser initialized. 16:03:29 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:29 INFO - TEST-INFO | started process 3073 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpIgIrLz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:41 INFO - PROCESS | 3073 | RSS: Main: 238411776 16:03:41 INFO - PROCESS | 3073 | 16:04:32 INFO - PROCESS | 3073 | 1461193472957 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] 16:06:44 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53716/tests/dromaeo/cssquery-ext.html) 16:06:46 INFO - PROCESS | 3073 | RSS: Main: 361418752 16:06:46 INFO - PROCESS | 3073 | 16:09:49 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-ext.html (next: http://localhost:53716/tests/dromaeo/cssquery-jquery.html) 16:09:49 INFO - PROCESS | 3073 | RSS: Main: 381734912 16:09:49 INFO - PROCESS | 3073 | 16:12:52 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53716/tests/dromaeo/cssquery-mootools.html) 16:12:52 INFO - PROCESS | 3073 | RSS: Main: 377675776 16:12:52 INFO - PROCESS | 3073 | 16:15:54 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53716/tests/dromaeo/cssquery-prototype.html) 16:15:55 INFO - PROCESS | 3073 | RSS: Main: 378933248 16:15:55 INFO - PROCESS | 3073 | 16:18:58 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53716/tests/dromaeo/cssquery-yui.html) 16:18:59 INFO - PROCESS | 3073 | RSS: Main: 347287552 16:18:59 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | Cycle 1(1): loaded http://localhost:53716/tests/dromaeo/cssquery-yui.html (next: http://localhost:53716/tests/dromaeo/cssquery-dojo.html) 16:22:02 INFO - PROCESS | 3073 | RSS: Main: 413982720 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | __start_tp_report 16:22:02 INFO - PROCESS | 3073 | _x_x_mozilla_page_load 16:22:02 INFO - PROCESS | 3073 | _x_x_mozilla_page_load_details 16:22:02 INFO - PROCESS | 3073 | |i|pagename|runs| 16:22:02 INFO - PROCESS | 3073 | |0;dojo.html;3331.633520312766;3374.105861946585;3386.9491957620626;3409.659034096591;3458.1873259783947;5596.300462442195;5670.858228544288;5682.090865461527;5693.28833895763;5704.714764261786;5668.57485688574;5736.799212027579;5753;5770.451807078321;5784.595078344507;5146.3309769729885;5172.6379153459275;5206.167013277876;5235.397929238148;5277.788888444457;5288.2067689846635;5297.390800057985;5299.655522391032;5300.231466437364;5306.257124002643;5120.615953803461;5203.349581302337;5208.947910520903;5230.006298803215;5327.627066105374;5262.710550919698;5272.103742363807;5276.472352764693;5291.968066227088;5329.893402131975;5093.796248150106;5129.9743501282255;5184.274201611777;5211.452797456288;5233.738313084368;5624.493795558378;5680.091185410356;5691.260136182311;5696.231008813798;5717.656940583578;6120.234970628671;6137.079438084278;6146.969265153691;6157.983932651107;6158.753649854;6052.833040037353;6125.7340447730285;6139.478144357735;6150.985087460564;6157;5723.112917497794;5795.478406943395;5806.738696758656;5851.765929362778;5877.9118313225335;6205.503559715212;6207.751689932352;6210.534209934273;6221.440070393686;6227.96886015567;6023.789167379121;6111.908321375183;6129.1419201311855;6161.7227224775;6167.229096362955;6192.690365481708;6195.907061394083;6197.070439434121;6200.0699895016105;6201.968990155111;6157.538184636171;6211.130441738161;6216.347283535164;6216.502679785697;6237.126802247689;6122.947104152868;6209.65846878417;6215.34738852423;6229.190205273286;6239;4813.975930120397;4837.105135549935;4870.634702397264;4878.268259761065;4894.8042078317185;4446.666500012448;4451.5103338632725;4468.709533880287;4487.102579484116;4502.279635258343;5711.171880077365;5716.714164291769;5728.598998070095;5740.397258287903;5750.769969201268;6135.5398345124295;6184.072389141575;6296.401841825019;6308;6337.33457986914;5700.600957932905;5766.308043034863;5787.2476578045425;5794.1308803679785;5812.244408226903;5347.705876176769;5376.623636345418;5379.973100134474;5388.030154572136;5392.029434701792;6186.381361863778;6234.2830574484515;6294.0558916162945;6303.243610766737;6321.936780632227;6269.62382257066;6283.434490895841;6341.112244285896;6347.143135676624;6352.333005034405;6295.464885484785;6308.053791931247;6316.494680425647;6339.049142628643;6341.239051313872;6091.177691011834;6134.5590473767;6152.861720581706;6228.408301211378;6248.593841400295;6270.310265870842;6276.309605943434;6276.686165691788;6283.3088360281245;6316.210473690789;6033.3061697905305;6105.267367915879;6113.755449782137;6126.571140020156;6128.662923539248;5971.492423144082;6078.635281883101;6125.724342404514;6136.478399336106;6138.263408391022;5980.43185897339;5980.641161530322;5989.670568118795;5997.16039754426;6007.969960150172;6111.205543279345;6121.632702037892;6123.112148738497;6123.234595675541;6139.079138129317;4311.288637374923;5785.30576330843;5998.430149135826;6009.06859436788;6067.817965461043 16:22:02 INFO - PROCESS | 3073 | |1;ext.html;501.43087595579004;502.36200025967025;528.1259515501845;556.6020295488725;571.6941436331564;29364;37535.62464375359;38752.612473875255;38755.80622096886;38846;40562;41326.793366033206;41342.37986430198;41538;41579;8422.989241291023;9330.486823224737;9534.09426104519;9541.998090200534;9593.856092158623;7557.093148822121;8533.701320453785;8669.436486628349;8685.956570217171;11229.382383968878;10440.955904409575;11176;11288.492017859175;11295.378754168558;11313.151513636469;10524.526396312145;11292.774144517145;11315.207935444521;11319.20765546412;11325.980661740401;7109.82225444368;9454.43273403591;11113.277636953653;11411.42942852854;11419.972202501815;37848.243035139145;38606.613933860586;38618.42072368917;38637.42043869344;38663.226735465134;3104.8758049677986;3244.7241984431353;3341.2315167511365;3405.4210784166503;3527.100589349725;28386;28583.66320611439;28766.137015889555;29355.85322073376;29471.557926631118;2716;2719.986400068007;2730.0581299451655;2741.972580274192;2853.9714602853915;459233;461463.69268153445;461466;462308.9999999966;462489;60129;60617.393826061176;60640.39359606436;60646.69676651677;60676.393236067954;63233.36766632367;63529.36470635327;63572;63590.682046590395;63656.68171659112;16056.27746751399;16673.6665266694;16709.41517046922;16728.66542669164;16746.74879876803;12824.294663793395;12931.870681293254;12941.805872911913;12945;12983.610491685076;14171.220582867834;15806.103267607961;16083.356665733474;16131.951423157692;16318.510444686679;5938.61399009063;6577.473802095821;6580.934190658032;6682.5322227444585;6690.464762818963;976.6581696406344;991.1822746234385;996.21299173652;1005.582683186484;1095.775366049958;29502.262443438656;29543.409131817672;29676;29696;29980.700192997792;983.680303901235;1085.9728506787235;1113.716002419378;1116.4640972333164;1211.6789050901518;14952.626184345261;15096;15250.008749431252;15253.847461525243;15440.659806144615;612994;617401.8259817344;620670;621988;622221;57967.710161449766;60064.099038514454;60113.699431502566;60175.39824601785;60348.09477857836;1307.4639397846838;1367.446184295382;1441.7909403136696;1479.8002269693668;1483.2806089046803;23601.40996475102;25419;28453.00414485483;28896.2775930595;30308.69691303147;48118.27822582594;48459;48661.7834554139;48663.27005094856;48698;404050;406945;407702;408406;410084;31369;32561.348773023932;39225.6077439222;39679.40480892731;42287.577124228366;43986.78006609946;44160;44985;45366.546334537554;45741.31388029249;36269.45595816012;36331.63668363282;36447.45328820123;37173.628263717015;37249.06877328089;35396.82301588476;35710.8214458926;35777.82111089532;36396.454053188696;36541.26917461582;36126;36215.84330663846;36360.81819590991;37149.44275835919;37211.81394093012;30131.849340753157;32137.35725285528;32267.51598725974;32627.694892204046;32904;21360.572788543832;23193.17148254436;23701.52596948086;24169.75830241675;24278.393040174138 16:22:02 INFO - PROCESS | 3073 | |2;jquery.html;397.4038941587618;400.53937971332965;402.9456023436837;405.5781986733797;411.3726566985347;14275.785863212033;19663.606727865452;20381.898090509527;20506.487337816543;20943.05756240969;15835.12906790126;17316.787824852265;17428.651426971453;20247.190112395525;20665.896670516628;5734.340550836655;5889.676067816268;5953.1070339449;5964.79123230687;5970.1940238067955;5394.91907621386;5765.509931655814;5873;5927.585069045169;5937.435943585352;3848.576656567789;3997.720729366604;4077;5053.469385714502;5896.675682837442;5890.882182356372;5895.646261224319;5914.822555323347;5917.023691090965;6032.849178770566;5872.324682661463;5884.558658100659;5910.083936989773;5912.822615321547;5920.615160014585;13508.93245533776;13658.453661853513;24280.63579046316;24625.63061554078;25188.748112518824;1978.4460351101714;2207.5033117548583;2281.8174546036275;2369.845960012594;2536.720960694322;9809.411435313832;9843.852342214872;9866.506674666238;10017.649382271658;10176.084152426318;1695.0592421206225;1701.651161511887;1715.802682691494;1719.8710096742668;1720.2001069502742;90714;112448;112527;121861;123672;11018.50416731249;11399.088072954144;11403.201775875776;11441.485133169028;11572.36352000648;14723.337449814784;14821.92589037048;14849.851501484847;14871.256437178097;14890.851091489163;7063.470239732041;8375.91113155298;8440.071592124861;8454.40819142654;8574.442661227;8280.792980175424;8294.875576866352;8298.626561804733;8421.24208821209;8428.957855210685;4360.215161271001;4471.709338892962;4479.820807167677;4536.115457485758;4543.204939135638;1486.3831509923477;1773.760542326795;1787.8212178782019;1950.2589016173763;1976.772671142837;852.4033176776281;939.7117424317454;961.5721004153224;1003.6738060130486;1026.5124066068558;17577.384791532237;18639.534011649546;19800.306989255307;20090.69863952042;20121.698174527395;938.4932136646271;940.7271891151627;942.3215284994793;969.7333233360741;986.8766404199475;10225.648100936825;10516.631917882838;10540.894591054144;10543.736406589744;10619.150467962545;584446;592032;600414;603887;604610;90769.54615227014;98483;127037.66440813184;127681;132280;889.3818795936762;944.9716508504756;956.7560272130562;1018.0735530667057;1020.897910208988;14129.929350353183;17249.8787578807;17522.82477175212;17555.94664320145;17946.384825365778;37923.81038094792;51903.740481297355;52166.21750673667;52773.47226527839;52923;70544.29455705377;73858;75277.62361188159;76344.0913977155;78106.6094669523;6534.640594767334;6685.543417352971;8117.512949223065;8188.795280117806;8519;8371.41400101987;10671.879452657358;11216.439178041228;11975.700607484534;12547.3098979557;9710.805783884423;10523.052925236765;10686.465676716289;11063.668089956993;11083.113350931744;10928.781424371628;10939.56241750321;10985.231033827555;11058.557657693924;11332.320060796379;10327.535260913126;11091.5563377464;11441.656750297172;11451.885481145415;11496.758132164148;7463.440241981764;8021.197880211932;8202.302804261466;8253.876191857007;8418.410711250399;9111.134442227978;10252.692419227136;10319.432431216355;10328;10520.473976301002 16:22:02 INFO - PROCESS | 3073 | |3;mootools.html;1418.6382472469518;1603.2945503978253;1610.3236640610942;1801.936932207373;1885.1422602715766;7919.56442395668;8645.913540864589;8876.8668469973;8891.06643802401;8900.73297801067;8496.150384961516;8756.8686469703;8757.956210218956;8758.649654013847;8767.868481972775;2173.2502286711137;2399.460121472672;2447.571674956884;2475.4306509502826;2482.8510289382602;2471.4192164841247;2498.725140234573;2502.324372419446;2507.3856905058287;2511.2466260121982;2390.079819269585;2439.9146029888957;2443.0472115874823;2443.132687895798;2445.29086564896;2124.511362386644;2140.678898165272;2364.302530753415;2438.3904023994;2454.4232105455203;2439.097533912446;2468.3829042739317;2473.4682043360763;2476.2818782553036;2480.1443501991785;8722.781930451725;8759.51822649754;8770.956145219297;8782.165694259034;8792.07683193262;4487.461504619434;5260.789568417259;5361.08861493547;5385.919211211835;5408.567314614821;3210.5184222366597;3281.36013477372;3423.3324501722172;3492.545969024036;3497.3704733148024;1015.4770293298883;1026.0406519903913;1028.9485525723685;1038.2991480750538;1089.624079692505;11590.942045289805;11667.59163429276;11719.179657423983;11723.531058757724;11753.94123029388;4681.602063824613;4812.975935120372;5073.797048118107;5075.441701412841;5103.311053007871;8566;8679.87161668979;9117.589708463134;9140.908590914005;9226.90773092274;5334.5732341412595;5346.759395827197;5346.8395948121615;5363.249145119687;5368.516833485005;4497.460304763449;4637.5130611286;4839.7096174229655;4994.700317980933;5027.396712394536;3993.560708322082;4023.3361495353524;4118.238125946709;4145.17096580685;4151;1220.1946715167946;1334.8598397168353;1344.4420565465223;1371.0882263294995;1377.2769296119618;850.6432240576271;853.7353420439683;880.3925291548939;893.6916763716507;950.1163917556601;4849.878753031202;5585.553155747531;5696.1455781633085;5763.366029736726;5822.621529600562;997.8253805584139;1037.258360272409;1049.4857519815237;1059.3485006720966;1063.3672964586058;3536.7701099428455;3667.651573100551;3672.265546890633;3720.051386896324;3742.8689995850013;10226.795464090825;10306.690799276033;10445.686629400978;10536.262461627612;10564;10496.317739346918;10498.527566259536;10503.317284376644;10563.154947604173;10666.306690065121;7665.961670191725;7978.720744773905;8119.83760324802;8133.959330203311;8143.552104634302;5648.71756412186;5655.688937108334;5782.277215348082;5807.477327040587;5839.357670656309;9441.669541566012;9592.328537002342;9695.806083878424;9788.902110979085;9813.607455701693;9715.125638692552;9891.35706179096;9894.109530142321;9947.204223662235;9969.60121595157;6846;7072.71709131629;7274.854502910018;7310.342069213583;7549.169591345058;8207.589620518831;8208.384371172306;8212.589370531568;8217.219364160395;8228.917710822816;7105.18290396611;7249.456290778107;7278.017467642012;7393.001944737607;7409.518381305198;7082.043924070391;7157.212706602374;7218.205997340393;7283.526570772883;7293;6855.691493882688;6945.826354341182;6949.270326615632;6952.096227490394;6958.269381714847;4693.718376897397;4746.979399429139;4808.975955120202;4886.584640305614;4958.975205123951;4808.927866082082;4840.419149701917;4860.513948605111;4919.212925931977;5070.619703522177 16:22:02 INFO - PROCESS | 3073 | |4;prototype.html;318.43636762929617;320.7129618991003;326.8529161877155;332.710541828609;335.7783862650651;4988.426330971939;5928.525717942565;5968.253968253974;5987.820365389034;6036.788712395067;4922.68002579833;5942.22751042365;5952.6130801497875;5966.850828729273;5978.461938425541;3968.1666849961543;4258.2747041893135;4348;4484.618807401374;4495.730256184639;4459.903845192771;4524.5701658342405;4538.7503687297185;4539.45526536816;4548.840790572331;3961.861334853281;4082.8976176432348;4496.595306422421;4539;4541.182587134314;3937.684985201177;4217.725847819897;4506.5493450655;4526.8868278292975;4540.614047805941;3305.3719793239447;3897.57126716061;3904.25819094374;3918.3730603103368;3922.627350401707;4737.857864264078;5653.660780353151;5667.1782591524;5687.004774164505;5700.401457846908;4941.481144479814;5225.581953443753;5240.292560504321;5258.737063146866;5307.6019298552765;5453.191606322747;5632.04255276604;6245.594036387621;6267.2165979252595;6276.117164850527;4290.613844753956;4326.9567304326865;4328.610425061728;4333.133373325348;4363.52001279859;5501.807436739695;6607.5374723769355;6623.271440141581;6645.60126392418;6650.5344625876205;4179.937300940488;4235.745855248695;4243.618074373259;4244.620498337991;4281.143771245701;3668.8899333020054;4297.935530967038;4299.247631664446;4313.525512193656;4314.346676793812;4659.930101048487;4895.437024742129;4931.654784165145;5114.795408183631;5117.437081920985;5790.073588225863;6266.028765541349;6365.3316401778075;6384.553081284265;6437.903431448532;1347.3128704360847;1438.3096113865404;1461.2693653173412;1482.9629259268604;1486.5986183730333;766.2950085920966;940.1303793990531;960.6301573894101;976.1897624971257;1021.1881554164308;4875.757151710182;5327.827877866863;5360.785568577291;5769.163471296639;5773.3072031356505;5554.916791225673;5857.799151173999;6044.335123136451;6101.003842483181;6493.902591461132;5168.080034780337;5301.231321458444;5482.725863706799;5553.500184983371;5659.405762394971;2645.7354264573387;2988.864231591981;3379.678930501598;3409.573803274591;3431.365197438482;6345.17512723343;6663.966680166568;6682.79951601443;6685.197776266879;6702.09521714572;5302.814401495929;6248.593841400295;6571.014347847766;6579.934200657932;6619.437347825488;5813.505852002542;6440.937245354464;6654.401103900575;6690.13028306317;6704.765333213314;5570.470805273492;5931.1399847021075;6145.231846019247;6575.671216439255;6583.407493325623;6402.583832050902;6492.513061520499;6497.772577959749;6500.739970401131;6548.770793022221;6485.383888530582;6821.965890170517;6829.053719689865;6841.144856892888;6865.828354291183;3846.0384903774057;4111.198316328407;4229.386738922777;4246.363045543193;4265.4454920861335;6010.1585777991995;6203.720832562455;6490.448311893542;6522.967385163044;6544.607323560602;6291.653959032297;6322.683865806599;6324.367563243639;6339.5562310637815;6354.028732042473;5313.920291195558;6282.780102696383;6284.277308109443;6324.620522768465;6358.141650877257;5127.307813445137;5957.344692083954;6097.7865774697675;6210.223722034746;6230.7507699691505;5106.7701953412925;5372.328458942632;6039.275286965592;6191.752329906753;6315.242170939517;6205.720742566686;6216.658083805434;6241;6249.562530622813;6256.906146407717 16:22:02 INFO - PROCESS | 3073 | |5;yui.html;666.1173944523507;692.183223795921;710.9964450177755;738.867003939291;811.1401913971191;11849.46677399517;15161.393544258217;15163.696726065473;15177.468788592401;15187.620309492268;15193.468228612;15880.205989700498;15906.76139857903;15923.124228167446;15933.521994340188;735.6101266328827;776.9844603107935;898.1243287794397;933.2953620016877;934.7569631895722;727.4907564704702;739.456499472887;747.4580928826612;749.9625018749041;890.398980688033;960.2605993385089;960.6253561111172;961.538461538462;962.8363178259677;972.4943029624591;949.5774380400725;953.1326492891435;953.2945620240972;954.0745476887408;964.1900803325206;570.8344580071774;585.871108356161;588.5438784941705;712.0494140322676;717.175248464265;12459.626211213677;14780.408783648641;15272.847271527253;15290.694186116216;15298.847011529853;12145.696357591043;12173.513059477698;12195.634130976085;12386.62840114798;12730.108892377537;9546.856797148048;9789.951050244703;9805.509724513817;10063.798724025552;10569.577216911315;3470.8611655534;3474.1488335357744;3481.582210134775;3573.213892943574;3584.910377240564;83260.58369708112;84290.57854710687;84416.99999999939;84652.57673711592;85660;9152.130547597968;9180.265578753817;9432.811343773086;9490.288228382899;10168.4915754212;21767;21767.56464870694;21790.019449124826;21817.890910545346;21850;7269.709211631475;7403.66683499233;7418.96290518544;7507.512011719221;7557;4021.4973128358956;4048.317669312892;4081.77550234734;4090.6727461802984;4154.771487568152;3496.3531746626954;3598.442241452579;3606.3688854450365;3610.5667319921254;3611.494390785292;584.7544031506777;588.5144755576666;623.9937600624027;637.0603360043991;656.5633853487473;511.24591227939237;542.926704894842;584.7777844419179;601.2634522709733;626.4612433307352;7523.3981281497345;7560.281773231644;7644.6942122315595;7689.615519224017;8146.348292136615;547.917812328154;577.3850848846049;578.2107423367157;590.6987436407463;599.2269971736498;3469.61834198238;3563.4476656118345;3634.545681789776;3733.5519737631657;3840.7695538267794;60570.69714651399;76695;77765;78148.34791641668;78430.60784696155;68389;68463;69245.65183700137;69471.65264173647;71230.931536027;60074.39925600688;60934.085988710205;61366.4658383544;61429;62662;7762.611869406394;7831.569263690551;7945.483543569881;8007.959960200162;8012.439129260896;35736.821315893256;36446.817765911;37997.430038549996;39388;40155.996100097735;55913.72043139758;56019.15971260353;56773;56866.715666421405;56950.7441707453;10525.578976840841;10549.630762923261;10841.945790270998;10842.891571084188;11064.22550421495;9533.570989305637;11802.76394472123;11882.524699012289;11961.162718609961;12053.276803391824;10838;10848.186386021238;10910;10975.341479511255;11072.391018493738;11017.504212310305;11022.669319920415;11085.667429977113;11294.548218071184;11321.981021707756;10458.63394781179;10592.523336449724;10740.62407815754;10878.728031799268;10940.398278094463;7907.2488113629115;9194.678186263449;9223.44659320443;9345.252379809463;9534.332596718163;7994.84010319802;8940.955295223483;9229.077092290718;9475.47884866339;9545.140937315675 16:22:02 INFO - PROCESS | 3073 | __end_tp_report 16:22:02 INFO - PROCESS | 3073 | __start_cc_report 16:22:02 INFO - PROCESS | 3073 | _x_x_mozilla_cycle_collect,1128 16:22:02 INFO - PROCESS | 3073 | __end_cc_report 16:22:02 INFO - PROCESS | 3073 | __startTimestamp1461194522716__endTimestamp 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | ------- Summary: start ------- 16:22:02 INFO - PROCESS | 3073 | Number of tests: 6 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#0] dojo.html Cycles:165 Average:5741.04 Median:5998.43 stddev:637.09 (10.6%) stddev-sans-first:610.36 16:22:02 INFO - PROCESS | 3073 | Values: 3331.6 3374.1 3386.9 3409.7 3458.2 5596.3 5670.9 5682.1 5693.3 5704.7 5668.6 5736.8 5753.0 5770.5 5784.6 5146.3 5172.6 5206.2 5235.4 5277.8 5288.2 5297.4 5299.7 5300.2 5306.3 5120.6 5203.3 5208.9 5230.0 5327.6 5262.7 5272.1 5276.5 5292.0 5329.9 5093.8 5130.0 5184.3 5211.5 5233.7 5624.5 5680.1 5691.3 5696.2 5717.7 6120.2 6137.1 6147.0 6158.0 6158.8 6052.8 6125.7 6139.5 6151.0 6157.0 5723.1 5795.5 5806.7 5851.8 5877.9 6205.5 6207.8 6210.5 6221.4 6228.0 6023.8 6111.9 6129.1 6161.7 6167.2 6192.7 6195.9 6197.1 6200.1 6202.0 6157.5 6211.1 6216.3 6216.5 6237.1 6122.9 6209.7 6215.3 6229.2 6239.0 4814.0 4837.1 4870.6 4878.3 4894.8 4446.7 4451.5 4468.7 4487.1 4502.3 5711.2 5716.7 5728.6 5740.4 5750.8 6135.5 6184.1 6296.4 6308.0 6337.3 5700.6 5766.3 5787.2 5794.1 5812.2 5347.7 5376.6 5380.0 5388.0 5392.0 6186.4 6234.3 6294.1 6303.2 6321.9 6269.6 6283.4 6341.1 6347.1 6352.3 6295.5 6308.1 6316.5 6339.0 6341.2 6091.2 6134.6 6152.9 6228.4 6248.6 6270.3 6276.3 6276.7 6283.3 6316.2 6033.3 6105.3 6113.8 6126.6 6128.7 5971.5 6078.6 6125.7 6136.5 6138.3 5980.4 5980.6 5989.7 5997.2 6008.0 6111.2 6121.6 6123.1 6123.2 6139.1 4311.3 5785.3 5998.4 6009.1 6067.8 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#1] ext.html Cycles:180 Average:63795.61 Median:36378.64 stddev:134404.58 (369.5%) stddev-sans-first:134697.61 16:22:02 INFO - PROCESS | 3073 | Values: 501.4 502.4 528.1 556.6 571.7 29364.0 37535.6 38752.6 38755.8 38846.0 40562.0 41326.8 41342.4 41538.0 41579.0 8423.0 9330.5 9534.1 9542.0 9593.9 7557.1 8533.7 8669.4 8686.0 11229.4 10441.0 11176.0 11288.5 11295.4 11313.2 10524.5 11292.8 11315.2 11319.2 11326.0 7109.8 9454.4 11113.3 11411.4 11420.0 37848.2 38606.6 38618.4 38637.4 38663.2 3104.9 3244.7 3341.2 3405.4 3527.1 28386.0 28583.7 28766.1 29355.9 29471.6 2716.0 2720.0 2730.1 2742.0 2854.0 459233.0 461463.7 461466.0 462309.0 462489.0 60129.0 60617.4 60640.4 60646.7 60676.4 63233.4 63529.4 63572.0 63590.7 63656.7 16056.3 16673.7 16709.4 16728.7 16746.7 12824.3 12931.9 12941.8 12945.0 12983.6 14171.2 15806.1 16083.4 16132.0 16318.5 5938.6 6577.5 6580.9 6682.5 6690.5 976.7 991.2 996.2 1005.6 1095.8 29502.3 29543.4 29676.0 29696.0 29980.7 983.7 1086.0 1113.7 1116.5 1211.7 14952.6 15096.0 15250.0 15253.8 15440.7 612994.0 617401.8 620670.0 621988.0 622221.0 57967.7 60064.1 60113.7 60175.4 60348.1 1307.5 1367.4 1441.8 1479.8 1483.3 23601.4 25419.0 28453.0 28896.3 30308.7 48118.3 48459.0 48661.8 48663.3 48698.0 404050.0 406945.0 407702.0 408406.0 410084.0 31369.0 32561.3 39225.6 39679.4 42287.6 43986.8 44160.0 44985.0 45366.5 45741.3 36269.5 36331.6 36447.5 37173.6 37249.1 35396.8 35710.8 35777.8 36396.5 36541.3 36126.0 36215.8 36360.8 37149.4 37211.8 30131.8 32137.4 32267.5 32627.7 32904.0 21360.6 23193.2 23701.5 24169.8 24278.4 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#2] jquery.html Cycles:180 Average:33808.93 Median:20886.19 stddev:99330.71 (475.6%) stddev-sans-first:99577.68 16:22:02 INFO - PROCESS | 3073 | Values: 397.4 400.5 402.9 405.6 411.4 14275.8 19663.6 20381.9 20506.5 20943.1 15835.1 17316.8 17428.7 20247.2 20665.9 5734.3 5889.7 5953.1 5964.8 5970.2 5394.9 5765.5 5873.0 5927.6 5937.4 3848.6 3997.7 4077.0 5053.5 5896.7 5890.9 5895.6 5914.8 5917.0 6032.8 5872.3 5884.6 5910.1 5912.8 5920.6 13508.9 13658.5 24280.6 24625.6 25188.7 1978.4 2207.5 2281.8 2369.8 2536.7 9809.4 9843.9 9866.5 10017.6 10176.1 1695.1 1701.7 1715.8 1719.9 1720.2 90714.0 112448.0 112527.0 121861.0 123672.0 11018.5 11399.1 11403.2 11441.5 11572.4 14723.3 14821.9 14849.9 14871.3 14890.9 7063.5 8375.9 8440.1 8454.4 8574.4 8280.8 8294.9 8298.6 8421.2 8429.0 4360.2 4471.7 4479.8 4536.1 4543.2 1486.4 1773.8 1787.8 1950.3 1976.8 852.4 939.7 961.6 1003.7 1026.5 17577.4 18639.5 19800.3 20090.7 20121.7 938.5 940.7 942.3 969.7 986.9 10225.6 10516.6 10540.9 10543.7 10619.2 584446.0 592032.0 600414.0 603887.0 604610.0 90769.5 98483.0 127037.7 127681.0 132280.0 889.4 945.0 956.8 1018.1 1020.9 14129.9 17249.9 17522.8 17555.9 17946.4 37923.8 51903.7 52166.2 52773.5 52923.0 70544.3 73858.0 75277.6 76344.1 78106.6 6534.6 6685.5 8117.5 8188.8 8519.0 8371.4 10671.9 11216.4 11975.7 12547.3 9710.8 10523.1 10686.5 11063.7 11083.1 10928.8 10939.6 10985.2 11058.6 11332.3 10327.5 11091.6 11441.7 11451.9 11496.8 7463.4 8021.2 8202.3 8253.9 8418.4 9111.1 10252.7 10319.4 10328.0 10520.5 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#3] mootools.html Cycles:180 Average:5628.14 Median:5011.05 stddev:3102.18 (61.9%) stddev-sans-first:3094.75 16:22:02 INFO - PROCESS | 3073 | Values: 1418.6 1603.3 1610.3 1801.9 1885.1 7919.6 8645.9 8876.9 8891.1 8900.7 8496.2 8756.9 8758.0 8758.6 8767.9 2173.3 2399.5 2447.6 2475.4 2482.9 2471.4 2498.7 2502.3 2507.4 2511.2 2390.1 2439.9 2443.0 2443.1 2445.3 2124.5 2140.7 2364.3 2438.4 2454.4 2439.1 2468.4 2473.5 2476.3 2480.1 8722.8 8759.5 8771.0 8782.2 8792.1 4487.5 5260.8 5361.1 5385.9 5408.6 3210.5 3281.4 3423.3 3492.5 3497.4 1015.5 1026.0 1028.9 1038.3 1089.6 11590.9 11667.6 11719.2 11723.5 11753.9 4681.6 4813.0 5073.8 5075.4 5103.3 8566.0 8679.9 9117.6 9140.9 9226.9 5334.6 5346.8 5346.8 5363.2 5368.5 4497.5 4637.5 4839.7 4994.7 5027.4 3993.6 4023.3 4118.2 4145.2 4151.0 1220.2 1334.9 1344.4 1371.1 1377.3 850.6 853.7 880.4 893.7 950.1 4849.9 5585.6 5696.1 5763.4 5822.6 997.8 1037.3 1049.5 1059.3 1063.4 3536.8 3667.7 3672.3 3720.1 3742.9 10226.8 10306.7 10445.7 10536.3 10564.0 10496.3 10498.5 10503.3 10563.2 10666.3 7666.0 7978.7 8119.8 8134.0 8143.6 5648.7 5655.7 5782.3 5807.5 5839.4 9441.7 9592.3 9695.8 9788.9 9813.6 9715.1 9891.4 9894.1 9947.2 9969.6 6846.0 7072.7 7274.9 7310.3 7549.2 8207.6 8208.4 8212.6 8217.2 8228.9 7105.2 7249.5 7278.0 7393.0 7409.5 7082.0 7157.2 7218.2 7283.5 7293.0 6855.7 6945.8 6949.3 6952.1 6958.3 4693.7 4747.0 4809.0 4886.6 4959.0 4808.9 4840.4 4860.5 4919.2 5070.6 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#4] prototype.html Cycles:180 Average:5061.79 Median:5642.85 stddev:1591.45 (28.2%) stddev-sans-first:1555.59 16:22:02 INFO - PROCESS | 3073 | Values: 318.4 320.7 326.9 332.7 335.8 4988.4 5928.5 5968.3 5987.8 6036.8 4922.7 5942.2 5952.6 5966.9 5978.5 3968.2 4258.3 4348.0 4484.6 4495.7 4459.9 4524.6 4538.8 4539.5 4548.8 3961.9 4082.9 4496.6 4539.0 4541.2 3937.7 4217.7 4506.5 4526.9 4540.6 3305.4 3897.6 3904.3 3918.4 3922.6 4737.9 5653.7 5667.2 5687.0 5700.4 4941.5 5225.6 5240.3 5258.7 5307.6 5453.2 5632.0 6245.6 6267.2 6276.1 4290.6 4327.0 4328.6 4333.1 4363.5 5501.8 6607.5 6623.3 6645.6 6650.5 4179.9 4235.7 4243.6 4244.6 4281.1 3668.9 4297.9 4299.2 4313.5 4314.3 4659.9 4895.4 4931.7 5114.8 5117.4 5790.1 6266.0 6365.3 6384.6 6437.9 1347.3 1438.3 1461.3 1483.0 1486.6 766.3 940.1 960.6 976.2 1021.2 4875.8 5327.8 5360.8 5769.2 5773.3 5554.9 5857.8 6044.3 6101.0 6493.9 5168.1 5301.2 5482.7 5553.5 5659.4 2645.7 2988.9 3379.7 3409.6 3431.4 6345.2 6664.0 6682.8 6685.2 6702.1 5302.8 6248.6 6571.0 6579.9 6619.4 5813.5 6440.9 6654.4 6690.1 6704.8 5570.5 5931.1 6145.2 6575.7 6583.4 6402.6 6492.5 6497.8 6500.7 6548.8 6485.4 6822.0 6829.1 6841.1 6865.8 3846.0 4111.2 4229.4 4246.4 4265.4 6010.2 6203.7 6490.4 6523.0 6544.6 6291.7 6322.7 6324.4 6339.6 6354.0 5313.9 6282.8 6284.3 6324.6 6358.1 5127.3 5957.3 6097.8 6210.2 6230.8 5106.8 5372.3 6039.3 6191.8 6315.2 6205.7 6216.7 6241.0 6249.6 6256.9 16:22:02 INFO - PROCESS | 3073 | 16:22:02 INFO - PROCESS | 3073 | [#5] yui.html Cycles:180 Average:16666.40 Median:587.19 stddev:22677.59 (3862.0%) stddev-sans-first:22709.38 16:22:02 INFO - PROCESS | 3073 | Values: 666.1 692.2 711.0 738.9 811.1 11849.5 15161.4 15163.7 15177.5 15187.6 15193.5 15880.2 15906.8 15923.1 15933.5 735.6 777.0 898.1 933.3 934.8 727.5 739.5 747.5 750.0 890.4 960.3 960.6 961.5 962.8 972.5 949.6 953.1 953.3 954.1 964.2 570.8 585.9 588.5 712.0 717.2 12459.6 14780.4 15272.8 15290.7 15298.8 12145.7 12173.5 12195.6 12386.6 12730.1 9546.9 9790.0 9805.5 10063.8 10569.6 3470.9 3474.1 3481.6 3573.2 3584.9 83260.6 84290.6 84417.0 84652.6 85660.0 9152.1 9180.3 9432.8 9490.3 10168.5 21767.0 21767.6 21790.0 21817.9 21850.0 7269.7 7403.7 7419.0 7507.5 7557.0 4021.5 4048.3 4081.8 4090.7 4154.8 3496.4 3598.4 3606.4 3610.6 3611.5 584.8 588.5 624.0 637.1 656.6 511.2 542.9 584.8 601.3 626.5 7523.4 7560.3 7644.7 7689.6 8146.3 547.9 577.4 578.2 590.7 599.2 3469.6 3563.4 3634.5 3733.6 3840.8 60570.7 76695.0 77765.0 78148.3 78430.6 68389.0 68463.0 69245.7 69471.7 71230.9 60074.4 60934.1 61366.5 61429.0 62662.0 7762.6 7831.6 7945.5 8008.0 8012.4 35736.8 36446.8 37997.4 39388.0 40156.0 55913.7 56019.2 56773.0 56866.7 56950.7 10525.6 10549.6 10841.9 10842.9 11064.2 9533.6 11802.8 11882.5 11961.2 12053.3 10838.0 10848.2 10910.0 10975.3 11072.4 11017.5 11022.7 11085.7 11294.5 11322.0 10458.6 10592.5 10740.6 10878.7 10940.4 7907.2 9194.7 9223.4 9345.3 9534.3 7994.8 8941.0 9229.1 9475.5 9545.1 16:22:02 INFO - PROCESS | 3073 | -------- Summary: end -------- 16:22:02 INFO - PROCESS | 3073 | 16:22:03 INFO - TEST-INFO | 3073: exit 0 16:22:03 INFO - TEST-OK | dromaeo_css | took 1116358ms 16:22:03 INFO - TEST-START | kraken 16:22:03 INFO - operating with platform_type : mac_ 16:22:03 INFO - Initialising browser for kraken test... 16:22:03 INFO - TEST-INFO | started process 3076 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp0SqTWR/profile http://localhost:53716/getInfo.html) 16:22:05 INFO - PROCESS | 3076 | __metrics Screen width/height:1600/1200 16:22:05 INFO - PROCESS | 3076 | colorDepth:24 16:22:05 INFO - PROCESS | 3076 | Browser inner width/height: 1024/689 16:22:05 INFO - PROCESS | 3076 | __metrics 16:22:05 INFO - PROCESS | 3076 | 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" 16:22:05 ERROR - PROCESS | 3076 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:22:06 INFO - TEST-INFO | 3076: exit 0 16:22:06 INFO - Browser initialized. 16:22:06 INFO - Running cycle 1/1 for kraken test... 16:22:06 INFO - TEST-INFO | started process 3078 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp0SqTWR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:18 INFO - PROCESS | 3078 | RSS: Main: 239476736 16:22:18 INFO - PROCESS | 3078 | 16:22:20 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53716/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:21 INFO - PROCESS | 3078 | RSS: Main: 286654464 16:22:21 INFO - PROCESS | 3078 | 16:22:24 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53716/tests/kraken/driver.html?testName=audio-dft) 16:22:25 INFO - PROCESS | 3078 | RSS: Main: 299999232 16:22:25 INFO - PROCESS | 3078 | 16:22:28 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53716/tests/kraken/driver.html?testName=audio-fft) 16:22:29 INFO - PROCESS | 3078 | RSS: Main: 388046848 16:22:29 INFO - PROCESS | 3078 | 16:22:31 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53716/tests/kraken/driver.html?testName=audio-oscillator) 16:22:31 INFO - PROCESS | 3078 | RSS: Main: 286302208 16:22:31 INFO - PROCESS | 3078 | 16:22:34 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53716/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:34 INFO - PROCESS | 3078 | RSS: Main: 290365440 16:22:34 INFO - PROCESS | 3078 | 16:22:37 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53716/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:38 INFO - PROCESS | 3078 | RSS: Main: 329392128 16:22:38 INFO - PROCESS | 3078 | 16:22:40 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53716/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:41 INFO - PROCESS | 3078 | RSS: Main: 328716288 16:22:41 INFO - PROCESS | 3078 | 16:22:44 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53716/tests/kraken/driver.html?testName=json-parse-financial) 16:22:44 INFO - PROCESS | 3078 | RSS: Main: 328781824 16:22:44 INFO - PROCESS | 3078 | 16:22:46 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53716/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:22:47 INFO - PROCESS | 3078 | RSS: Main: 320864256 16:22:47 INFO - PROCESS | 3078 | 16:22:48 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:22:49 INFO - PROCESS | 3078 | RSS: Main: 328531968 16:22:49 INFO - PROCESS | 3078 | 16:22:51 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:22:52 INFO - PROCESS | 3078 | RSS: Main: 330379264 16:22:52 INFO - PROCESS | 3078 | 16:22:55 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:22:55 INFO - PROCESS | 3078 | RSS: Main: 335511552 16:22:55 INFO - PROCESS | 3078 | 16:22:58 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:22:59 INFO - PROCESS | 3078 | RSS: Main: 332730368 16:22:59 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | Cycle 1(1): loaded http://localhost:53716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53716/tests/kraken/driver.html?testName=ai-astar) 16:23:01 INFO - PROCESS | 3078 | RSS: Main: 397537280 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | __start_tp_report 16:23:01 INFO - PROCESS | 3078 | _x_x_mozilla_page_load 16:23:01 INFO - PROCESS | 3078 | _x_x_mozilla_page_load_details 16:23:01 INFO - PROCESS | 3078 | |i|pagename|runs| 16:23:01 INFO - PROCESS | 3078 | |0;ai-astar;113;109;159;164;112;111;118;112;113;111 16:23:01 INFO - PROCESS | 3078 | |1;audio-beat-detection;159;120;144;170;120;145;140;121;171;119 16:23:01 INFO - PROCESS | 3078 | |2;audio-dft;189;190;188;186;186;187;186;185;182;185 16:23:01 INFO - PROCESS | 3078 | |3;audio-fft;77;73;91;93;56;59;58;80;84;56 16:23:01 INFO - PROCESS | 3078 | |4;audio-oscillator;95;98;138;75;79;81;125;77;88;161 16:23:01 INFO - PROCESS | 3078 | |5;imaging-gaussian-blur;91;85;89;86;88;85;87;88;88;85 16:23:01 INFO - PROCESS | 3078 | |6;imaging-darkroom;100;94;93;93;93;94;94;94;93;93 16:23:01 INFO - PROCESS | 3078 | |7;imaging-desaturate;84;82;83;87;83;82;84;85;85;86 16:23:01 INFO - PROCESS | 3078 | |8;json-parse-financial;59;61;58;58;57;57;56;77;59;56 16:23:01 INFO - PROCESS | 3078 | |9;json-stringify-tinderbox;62;72;78;76;75;71;69;66;69;95 16:23:01 INFO - PROCESS | 3078 | |10;stanford-crypto-aes;82;98;98;82;85;81;81;80;83;80 16:23:01 INFO - PROCESS | 3078 | |11;stanford-crypto-ccm;139;138;139;136;134;140;141;158;192;141 16:23:01 INFO - PROCESS | 3078 | |12;stanford-crypto-pbkdf2;170;165;167;168;165;168;178;176;168;172 16:23:01 INFO - PROCESS | 3078 | |13;stanford-crypto-sha256-iterative;70;69;68;66;69;67;68;69;69;65 16:23:01 INFO - PROCESS | 3078 | __end_tp_report 16:23:01 INFO - PROCESS | 3078 | __start_cc_report 16:23:01 INFO - PROCESS | 3078 | _x_x_mozilla_cycle_collect,2034 16:23:01 INFO - PROCESS | 3078 | __end_cc_report 16:23:01 INFO - PROCESS | 3078 | __startTimestamp1461194581407__endTimestamp 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | ------- Summary: start ------- 16:23:01 INFO - PROCESS | 3078 | Number of tests: 14 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#0] ai-astar Cycles:10 Average:122.20 Median:113.00 stddev:20.87 (18.5%) stddev-sans-first:21.87 16:23:01 INFO - PROCESS | 3078 | Values: 113.0 109.0 159.0 164.0 112.0 111.0 118.0 112.0 113.0 111.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:144.50 stddev:20.70 (14.3%) stddev-sans-first:20.90 16:23:01 INFO - PROCESS | 3078 | Values: 159.0 120.0 144.0 170.0 120.0 145.0 140.0 121.0 171.0 119.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#2] audio-dft Cycles:10 Average:186.40 Median:186.50 stddev:2.27 (1.2%) stddev-sans-first:2.20 16:23:01 INFO - PROCESS | 3078 | Values: 189.0 190.0 188.0 186.0 186.0 187.0 186.0 185.0 182.0 185.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#3] audio-fft Cycles:10 Average:72.70 Median:78.50 stddev:14.56 (18.5%) stddev-sans-first:15.36 16:23:01 INFO - PROCESS | 3078 | Values: 77.0 73.0 91.0 93.0 56.0 59.0 58.0 80.0 84.0 56.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:80.00 stddev:29.61 (37.0%) stddev-sans-first:31.31 16:23:01 INFO - PROCESS | 3078 | Values: 95.0 98.0 138.0 75.0 79.0 81.0 125.0 77.0 88.0 161.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.99 (2.3%) stddev-sans-first:1.56 16:23:01 INFO - PROCESS | 3078 | Values: 91.0 85.0 89.0 86.0 88.0 85.0 87.0 88.0 88.0 85.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.50 stddev:2.13 (2.3%) stddev-sans-first:0.53 16:23:01 INFO - PROCESS | 3078 | Values: 100.0 94.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 93.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:1.66 (2.0%) stddev-sans-first:1.76 16:23:01 INFO - PROCESS | 3078 | Values: 84.0 82.0 83.0 87.0 83.0 82.0 84.0 85.0 85.0 86.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:58.50 stddev:6.23 (10.7%) stddev-sans-first:6.60 16:23:01 INFO - PROCESS | 3078 | Values: 59.0 61.0 58.0 58.0 57.0 57.0 56.0 77.0 59.0 56.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.50 stddev:8.99 (12.2%) stddev-sans-first:8.56 16:23:01 INFO - PROCESS | 3078 | Values: 62.0 72.0 78.0 76.0 75.0 71.0 69.0 66.0 69.0 95.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:82.50 stddev:7.01 (8.5%) stddev-sans-first:7.35 16:23:01 INFO - PROCESS | 3078 | Values: 82.0 98.0 98.0 82.0 85.0 81.0 81.0 80.0 83.0 80.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:140.50 stddev:17.49 (12.4%) stddev-sans-first:18.37 16:23:01 INFO - PROCESS | 3078 | Values: 139.0 138.0 139.0 136.0 134.0 140.0 141.0 158.0 192.0 141.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:169.00 stddev:4.40 (2.6%) stddev-sans-first:4.66 16:23:01 INFO - PROCESS | 3078 | Values: 170.0 165.0 167.0 168.0 165.0 168.0 178.0 176.0 168.0 172.0 16:23:01 INFO - PROCESS | 3078 | 16:23:01 INFO - PROCESS | 3078 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:69.00 stddev:1.56 (2.3%) stddev-sans-first:1.48 16:23:01 INFO - PROCESS | 3078 | Values: 70.0 69.0 68.0 66.0 69.0 67.0 68.0 69.0 69.0 65.0 16:23:01 INFO - PROCESS | 3078 | -------- Summary: end -------- 16:23:01 INFO - PROCESS | 3078 | 16:23:02 INFO - TEST-INFO | 3078: exit 0 16:23:02 INFO - TEST-OK | kraken | took 58952ms 16:23:02 INFO - SUITE-END | took 1175s 16:23:02 INFO - Completed test suite (00:19:35) 16:23:02 INFO - javascript benchmark 16:23:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.633520312766, 3374.105861946585, 3386.9491957620626, 3409.659034096591, 3458.1873259783947, 5596.300462442195, 5670.858228544288, 5682.090865461527, 5693.28833895763, 5704.714764261786, 5668.57485688574, 5736.799212027579, 5753.0, 5770.451807078321, 5784.595078344507, 5146.3309769729885, 5172.6379153459275, 5206.167013277876, 5235.397929238148, 5277.788888444457, 5288.2067689846635, 5297.390800057985, 5299.655522391032, 5300.231466437364, 5306.257124002643, 5120.615953803461, 5203.349581302337, 5208.947910520903, 5230.006298803215, 5327.627066105374, 5262.710550919698, 5272.103742363807, 5276.472352764693, 5291.968066227088, 5329.893402131975, 5093.796248150106, 5129.9743501282255, 5184.274201611777, 5211.452797456288, 5233.738313084368, 5624.493795558378, 5680.091185410356, 5691.260136182311, 5696.231008813798, 5717.656940583578, 6120.234970628671, 6137.079438084278, 6146.969265153691, 6157.983932651107, 6158.753649854, 6052.833040037353, 6125.7340447730285, 6139.478144357735, 6150.985087460564, 6157.0, 5723.112917497794, 5795.478406943395, 5806.738696758656, 5851.765929362778, 5877.9118313225335, 6205.503559715212, 6207.751689932352, 6210.534209934273, 6221.440070393686, 6227.96886015567, 6023.789167379121, 6111.908321375183, 6129.1419201311855, 6161.7227224775, 6167.229096362955, 6192.690365481708, 6195.907061394083, 6197.070439434121, 6200.0699895016105, 6201.968990155111, 6157.538184636171, 6211.130441738161, 6216.347283535164, 6216.502679785697, 6237.126802247689, 6122.947104152868, 6209.65846878417, 6215.34738852423, 6229.190205273286, 6239.0, 4813.975930120397, 4837.105135549935, 4870.634702397264, 4878.268259761065, 4894.8042078317185, 4446.666500012448, 4451.5103338632725, 4468.709533880287, 4487.102579484116, 4502.279635258343, 5711.171880077365, 5716.714164291769, 5728.598998070095, 5740.397258287903, 5750.769969201268, 6135.5398345124295, 6184.072389141575, 6296.401841825019, 6308.0, 6337.33457986914, 5700.600957932905, 5766.308043034863, 5787.2476578045425, 5794.1308803679785, 5812.244408226903, 5347.705876176769, 5376.623636345418, 5379.973100134474, 5388.030154572136, 5392.029434701792, 6186.381361863778, 6234.2830574484515, 6294.0558916162945, 6303.243610766737, 6321.936780632227, 6269.62382257066, 6283.434490895841, 6341.112244285896, 6347.143135676624, 6352.333005034405, 6295.464885484785, 6308.053791931247, 6316.494680425647, 6339.049142628643, 6341.239051313872, 6091.177691011834, 6134.5590473767, 6152.861720581706, 6228.408301211378, 6248.593841400295, 6270.310265870842, 6276.309605943434, 6276.686165691788, 6283.3088360281245, 6316.210473690789, 6033.3061697905305, 6105.267367915879, 6113.755449782137, 6126.571140020156, 6128.662923539248, 5971.492423144082, 6078.635281883101, 6125.724342404514, 6136.478399336106, 6138.263408391022, 5980.43185897339, 5980.641161530322, 5989.670568118795, 5997.16039754426, 6007.969960150172, 6111.205543279345, 6121.632702037892, 6123.112148738497, 6123.234595675541, 6139.079138129317, 4311.288637374923, 5785.30576330843, 5998.430149135826, 6009.06859436788, 6067.817965461043], "value": 5700.805125802427, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.43087595579004, 502.36200025967025, 528.1259515501845, 556.6020295488725, 571.6941436331564, 29364.0, 37535.62464375359, 38752.612473875255, 38755.80622096886, 38846.0, 40562.0, 41326.793366033206, 41342.37986430198, 41538.0, 41579.0, 8422.989241291023, 9330.486823224737, 9534.09426104519, 9541.998090200534, 9593.856092158623, 7557.093148822121, 8533.701320453785, 8669.436486628349, 8685.956570217171, 11229.382383968878, 10440.955904409575, 11176.0, 11288.492017859175, 11295.378754168558, 11313.151513636469, 10524.526396312145, 11292.774144517145, 11315.207935444521, 11319.20765546412, 11325.980661740401, 7109.82225444368, 9454.43273403591, 11113.277636953653, 11411.42942852854, 11419.972202501815, 37848.243035139145, 38606.613933860586, 38618.42072368917, 38637.42043869344, 38663.226735465134, 3104.8758049677986, 3244.7241984431353, 3341.2315167511365, 3405.4210784166503, 3527.100589349725, 28386.0, 28583.66320611439, 28766.137015889555, 29355.85322073376, 29471.557926631118, 2716.0, 2719.986400068007, 2730.0581299451655, 2741.972580274192, 2853.9714602853915, 459233.0, 461463.69268153445, 461466.0, 462308.9999999966, 462489.0, 60129.0, 60617.393826061176, 60640.39359606436, 60646.69676651677, 60676.393236067954, 63233.36766632367, 63529.36470635327, 63572.0, 63590.682046590395, 63656.68171659112, 16056.27746751399, 16673.6665266694, 16709.41517046922, 16728.66542669164, 16746.74879876803, 12824.294663793395, 12931.870681293254, 12941.805872911913, 12945.0, 12983.610491685076, 14171.220582867834, 15806.103267607961, 16083.356665733474, 16131.951423157692, 16318.510444686679, 5938.61399009063, 6577.473802095821, 6580.934190658032, 6682.5322227444585, 6690.464762818963, 976.6581696406344, 991.1822746234385, 996.21299173652, 1005.582683186484, 1095.775366049958, 29502.262443438656, 29543.409131817672, 29676.0, 29696.0, 29980.700192997792, 983.680303901235, 1085.9728506787235, 1113.716002419378, 1116.4640972333164, 1211.6789050901518, 14952.626184345261, 15096.0, 15250.008749431252, 15253.847461525243, 15440.659806144615, 612994.0, 617401.8259817344, 620670.0, 621988.0, 622221.0, 57967.710161449766, 60064.099038514454, 60113.699431502566, 60175.39824601785, 60348.09477857836, 1307.4639397846838, 1367.446184295382, 1441.7909403136696, 1479.8002269693668, 1483.2806089046803, 23601.40996475102, 25419.0, 28453.00414485483, 28896.2775930595, 30308.69691303147, 48118.27822582594, 48459.0, 48661.7834554139, 48663.27005094856, 48698.0, 404050.0, 406945.0, 407702.0, 408406.0, 410084.0, 31369.0, 32561.348773023932, 39225.6077439222, 39679.40480892731, 42287.577124228366, 43986.78006609946, 44160.0, 44985.0, 45366.546334537554, 45741.31388029249, 36269.45595816012, 36331.63668363282, 36447.45328820123, 37173.628263717015, 37249.06877328089, 35396.82301588476, 35710.8214458926, 35777.82111089532, 36396.454053188696, 36541.26917461582, 36126.0, 36215.84330663846, 36360.81819590991, 37149.44275835919, 37211.81394093012, 30131.849340753157, 32137.35725285528, 32267.51598725974, 32627.694892204046, 32904.0, 21360.572788543832, 23193.17148254436, 23701.52596948086, 24169.75830241675, 24278.393040174138], "value": 19513.29733164198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.4038941587618, 400.53937971332965, 402.9456023436837, 405.5781986733797, 411.3726566985347, 14275.785863212033, 19663.606727865452, 20381.898090509527, 20506.487337816543, 20943.05756240969, 15835.12906790126, 17316.787824852265, 17428.651426971453, 20247.190112395525, 20665.896670516628, 5734.340550836655, 5889.676067816268, 5953.1070339449, 5964.79123230687, 5970.1940238067955, 5394.91907621386, 5765.509931655814, 5873.0, 5927.585069045169, 5937.435943585352, 3848.576656567789, 3997.720729366604, 4077.0, 5053.469385714502, 5896.675682837442, 5890.882182356372, 5895.646261224319, 5914.822555323347, 5917.023691090965, 6032.849178770566, 5872.324682661463, 5884.558658100659, 5910.083936989773, 5912.822615321547, 5920.615160014585, 13508.93245533776, 13658.453661853513, 24280.63579046316, 24625.63061554078, 25188.748112518824, 1978.4460351101714, 2207.5033117548583, 2281.8174546036275, 2369.845960012594, 2536.720960694322, 9809.411435313832, 9843.852342214872, 9866.506674666238, 10017.649382271658, 10176.084152426318, 1695.0592421206225, 1701.651161511887, 1715.802682691494, 1719.8710096742668, 1720.2001069502742, 90714.0, 112448.0, 112527.0, 121861.0, 123672.0, 11018.50416731249, 11399.088072954144, 11403.201775875776, 11441.485133169028, 11572.36352000648, 14723.337449814784, 14821.92589037048, 14849.851501484847, 14871.256437178097, 14890.851091489163, 7063.470239732041, 8375.91113155298, 8440.071592124861, 8454.40819142654, 8574.442661227, 8280.792980175424, 8294.875576866352, 8298.626561804733, 8421.24208821209, 8428.957855210685, 4360.215161271001, 4471.709338892962, 4479.820807167677, 4536.115457485758, 4543.204939135638, 1486.3831509923477, 1773.760542326795, 1787.8212178782019, 1950.2589016173763, 1976.772671142837, 852.4033176776281, 939.7117424317454, 961.5721004153224, 1003.6738060130486, 1026.5124066068558, 17577.384791532237, 18639.534011649546, 19800.306989255307, 20090.69863952042, 20121.698174527395, 938.4932136646271, 940.7271891151627, 942.3215284994793, 969.7333233360741, 986.8766404199475, 10225.648100936825, 10516.631917882838, 10540.894591054144, 10543.736406589744, 10619.150467962545, 584446.0, 592032.0, 600414.0, 603887.0, 604610.0, 90769.54615227014, 98483.0, 127037.66440813184, 127681.0, 132280.0, 889.3818795936762, 944.9716508504756, 956.7560272130562, 1018.0735530667057, 1020.897910208988, 14129.929350353183, 17249.8787578807, 17522.82477175212, 17555.94664320145, 17946.384825365778, 37923.81038094792, 51903.740481297355, 52166.21750673667, 52773.47226527839, 52923.0, 70544.29455705377, 73858.0, 75277.62361188159, 76344.0913977155, 78106.6094669523, 6534.640594767334, 6685.543417352971, 8117.512949223065, 8188.795280117806, 8519.0, 8371.41400101987, 10671.879452657358, 11216.439178041228, 11975.700607484534, 12547.3098979557, 9710.805783884423, 10523.052925236765, 10686.465676716289, 11063.668089956993, 11083.113350931744, 10928.781424371628, 10939.56241750321, 10985.231033827555, 11058.557657693924, 11332.320060796379, 10327.535260913126, 11091.5563377464, 11441.656750297172, 11451.885481145415, 11496.758132164148, 7463.440241981764, 8021.197880211932, 8202.302804261466, 8253.876191857007, 8418.410711250399, 9111.134442227978, 10252.692419227136, 10319.432431216355, 10328.0, 10520.473976301002], "value": 9164.33826633262, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.6382472469518, 1603.2945503978253, 1610.3236640610942, 1801.936932207373, 1885.1422602715766, 7919.56442395668, 8645.913540864589, 8876.8668469973, 8891.06643802401, 8900.73297801067, 8496.150384961516, 8756.8686469703, 8757.956210218956, 8758.649654013847, 8767.868481972775, 2173.2502286711137, 2399.460121472672, 2447.571674956884, 2475.4306509502826, 2482.8510289382602, 2471.4192164841247, 2498.725140234573, 2502.324372419446, 2507.3856905058287, 2511.2466260121982, 2390.079819269585, 2439.9146029888957, 2443.0472115874823, 2443.132687895798, 2445.29086564896, 2124.511362386644, 2140.678898165272, 2364.302530753415, 2438.3904023994, 2454.4232105455203, 2439.097533912446, 2468.3829042739317, 2473.4682043360763, 2476.2818782553036, 2480.1443501991785, 8722.781930451725, 8759.51822649754, 8770.956145219297, 8782.165694259034, 8792.07683193262, 4487.461504619434, 5260.789568417259, 5361.08861493547, 5385.919211211835, 5408.567314614821, 3210.5184222366597, 3281.36013477372, 3423.3324501722172, 3492.545969024036, 3497.3704733148024, 1015.4770293298883, 1026.0406519903913, 1028.9485525723685, 1038.2991480750538, 1089.624079692505, 11590.942045289805, 11667.59163429276, 11719.179657423983, 11723.531058757724, 11753.94123029388, 4681.602063824613, 4812.975935120372, 5073.797048118107, 5075.441701412841, 5103.311053007871, 8566.0, 8679.87161668979, 9117.589708463134, 9140.908590914005, 9226.90773092274, 5334.5732341412595, 5346.759395827197, 5346.8395948121615, 5363.249145119687, 5368.516833485005, 4497.460304763449, 4637.5130611286, 4839.7096174229655, 4994.700317980933, 5027.396712394536, 3993.560708322082, 4023.3361495353524, 4118.238125946709, 4145.17096580685, 4151.0, 1220.1946715167946, 1334.8598397168353, 1344.4420565465223, 1371.0882263294995, 1377.2769296119618, 850.6432240576271, 853.7353420439683, 880.3925291548939, 893.6916763716507, 950.1163917556601, 4849.878753031202, 5585.553155747531, 5696.1455781633085, 5763.366029736726, 5822.621529600562, 997.8253805584139, 1037.258360272409, 1049.4857519815237, 1059.3485006720966, 1063.3672964586058, 3536.7701099428455, 3667.651573100551, 3672.265546890633, 3720.051386896324, 3742.8689995850013, 10226.795464090825, 10306.690799276033, 10445.686629400978, 10536.262461627612, 10564.0, 10496.317739346918, 10498.527566259536, 10503.317284376644, 10563.154947604173, 10666.306690065121, 7665.961670191725, 7978.720744773905, 8119.83760324802, 8133.959330203311, 8143.552104634302, 5648.71756412186, 5655.688937108334, 5782.277215348082, 5807.477327040587, 5839.357670656309, 9441.669541566012, 9592.328537002342, 9695.806083878424, 9788.902110979085, 9813.607455701693, 9715.125638692552, 9891.35706179096, 9894.109530142321, 9947.204223662235, 9969.60121595157, 6846.0, 7072.71709131629, 7274.854502910018, 7310.342069213583, 7549.169591345058, 8207.589620518831, 8208.384371172306, 8212.589370531568, 8217.219364160395, 8228.917710822816, 7105.18290396611, 7249.456290778107, 7278.017467642012, 7393.001944737607, 7409.518381305198, 7082.043924070391, 7157.212706602374, 7218.205997340393, 7283.526570772883, 7293.0, 6855.691493882688, 6945.826354341182, 6949.270326615632, 6952.096227490394, 6958.269381714847, 4693.718376897397, 4746.979399429139, 4808.975955120202, 4886.584640305614, 4958.975205123951, 4808.927866082082, 4840.419149701917, 4860.513948605111, 4919.212925931977, 5070.619703522177], "value": 4565.853151955483, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.43636762929617, 320.7129618991003, 326.8529161877155, 332.710541828609, 335.7783862650651, 4988.426330971939, 5928.525717942565, 5968.253968253974, 5987.820365389034, 6036.788712395067, 4922.68002579833, 5942.22751042365, 5952.6130801497875, 5966.850828729273, 5978.461938425541, 3968.1666849961543, 4258.2747041893135, 4348.0, 4484.618807401374, 4495.730256184639, 4459.903845192771, 4524.5701658342405, 4538.7503687297185, 4539.45526536816, 4548.840790572331, 3961.861334853281, 4082.8976176432348, 4496.595306422421, 4539.0, 4541.182587134314, 3937.684985201177, 4217.725847819897, 4506.5493450655, 4526.8868278292975, 4540.614047805941, 3305.3719793239447, 3897.57126716061, 3904.25819094374, 3918.3730603103368, 3922.627350401707, 4737.857864264078, 5653.660780353151, 5667.1782591524, 5687.004774164505, 5700.401457846908, 4941.481144479814, 5225.581953443753, 5240.292560504321, 5258.737063146866, 5307.6019298552765, 5453.191606322747, 5632.04255276604, 6245.594036387621, 6267.2165979252595, 6276.117164850527, 4290.613844753956, 4326.9567304326865, 4328.610425061728, 4333.133373325348, 4363.52001279859, 5501.807436739695, 6607.5374723769355, 6623.271440141581, 6645.60126392418, 6650.5344625876205, 4179.937300940488, 4235.745855248695, 4243.618074373259, 4244.620498337991, 4281.143771245701, 3668.8899333020054, 4297.935530967038, 4299.247631664446, 4313.525512193656, 4314.346676793812, 4659.930101048487, 4895.437024742129, 4931.654784165145, 5114.795408183631, 5117.437081920985, 5790.073588225863, 6266.028765541349, 6365.3316401778075, 6384.553081284265, 6437.903431448532, 1347.3128704360847, 1438.3096113865404, 1461.2693653173412, 1482.9629259268604, 1486.5986183730333, 766.2950085920966, 940.1303793990531, 960.6301573894101, 976.1897624971257, 1021.1881554164308, 4875.757151710182, 5327.827877866863, 5360.785568577291, 5769.163471296639, 5773.3072031356505, 5554.916791225673, 5857.799151173999, 6044.335123136451, 6101.003842483181, 6493.902591461132, 5168.080034780337, 5301.231321458444, 5482.725863706799, 5553.500184983371, 5659.405762394971, 2645.7354264573387, 2988.864231591981, 3379.678930501598, 3409.573803274591, 3431.365197438482, 6345.17512723343, 6663.966680166568, 6682.79951601443, 6685.197776266879, 6702.09521714572, 5302.814401495929, 6248.593841400295, 6571.014347847766, 6579.934200657932, 6619.437347825488, 5813.505852002542, 6440.937245354464, 6654.401103900575, 6690.13028306317, 6704.765333213314, 5570.470805273492, 5931.1399847021075, 6145.231846019247, 6575.671216439255, 6583.407493325623, 6402.583832050902, 6492.513061520499, 6497.772577959749, 6500.739970401131, 6548.770793022221, 6485.383888530582, 6821.965890170517, 6829.053719689865, 6841.144856892888, 6865.828354291183, 3846.0384903774057, 4111.198316328407, 4229.386738922777, 4246.363045543193, 4265.4454920861335, 6010.1585777991995, 6203.720832562455, 6490.448311893542, 6522.967385163044, 6544.607323560602, 6291.653959032297, 6322.683865806599, 6324.367563243639, 6339.5562310637815, 6354.028732042473, 5313.920291195558, 6282.780102696383, 6284.277308109443, 6324.620522768465, 6358.141650877257, 5127.307813445137, 5957.344692083954, 6097.7865774697675, 6210.223722034746, 6230.7507699691505, 5106.7701953412925, 5372.328458942632, 6039.275286965592, 6191.752329906753, 6315.242170939517, 6205.720742566686, 6216.658083805434, 6241.0, 6249.562530622813, 6256.906146407717], "value": 4544.562116959859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.1173944523507, 692.183223795921, 710.9964450177755, 738.867003939291, 811.1401913971191, 11849.46677399517, 15161.393544258217, 15163.696726065473, 15177.468788592401, 15187.620309492268, 15193.468228612, 15880.205989700498, 15906.76139857903, 15923.124228167446, 15933.521994340188, 735.6101266328827, 776.9844603107935, 898.1243287794397, 933.2953620016877, 934.7569631895722, 727.4907564704702, 739.456499472887, 747.4580928826612, 749.9625018749041, 890.398980688033, 960.2605993385089, 960.6253561111172, 961.538461538462, 962.8363178259677, 972.4943029624591, 949.5774380400725, 953.1326492891435, 953.2945620240972, 954.0745476887408, 964.1900803325206, 570.8344580071774, 585.871108356161, 588.5438784941705, 712.0494140322676, 717.175248464265, 12459.626211213677, 14780.408783648641, 15272.847271527253, 15290.694186116216, 15298.847011529853, 12145.696357591043, 12173.513059477698, 12195.634130976085, 12386.62840114798, 12730.108892377537, 9546.856797148048, 9789.951050244703, 9805.509724513817, 10063.798724025552, 10569.577216911315, 3470.8611655534, 3474.1488335357744, 3481.582210134775, 3573.213892943574, 3584.910377240564, 83260.58369708112, 84290.57854710687, 84416.99999999939, 84652.57673711592, 85660.0, 9152.130547597968, 9180.265578753817, 9432.811343773086, 9490.288228382899, 10168.4915754212, 21767.0, 21767.56464870694, 21790.019449124826, 21817.890910545346, 21850.0, 7269.709211631475, 7403.66683499233, 7418.96290518544, 7507.512011719221, 7557.0, 4021.4973128358956, 4048.317669312892, 4081.77550234734, 4090.6727461802984, 4154.771487568152, 3496.3531746626954, 3598.442241452579, 3606.3688854450365, 3610.5667319921254, 3611.494390785292, 584.7544031506777, 588.5144755576666, 623.9937600624027, 637.0603360043991, 656.5633853487473, 511.24591227939237, 542.926704894842, 584.7777844419179, 601.2634522709733, 626.4612433307352, 7523.3981281497345, 7560.281773231644, 7644.6942122315595, 7689.615519224017, 8146.348292136615, 547.917812328154, 577.3850848846049, 578.2107423367157, 590.6987436407463, 599.2269971736498, 3469.61834198238, 3563.4476656118345, 3634.545681789776, 3733.5519737631657, 3840.7695538267794, 60570.69714651399, 76695.0, 77765.0, 78148.34791641668, 78430.60784696155, 68389.0, 68463.0, 69245.65183700137, 69471.65264173647, 71230.931536027, 60074.39925600688, 60934.085988710205, 61366.4658383544, 61429.0, 62662.0, 7762.611869406394, 7831.569263690551, 7945.483543569881, 8007.959960200162, 8012.439129260896, 35736.821315893256, 36446.817765911, 37997.430038549996, 39388.0, 40155.996100097735, 55913.72043139758, 56019.15971260353, 56773.0, 56866.715666421405, 56950.7441707453, 10525.578976840841, 10549.630762923261, 10841.945790270998, 10842.891571084188, 11064.22550421495, 9533.570989305637, 11802.76394472123, 11882.524699012289, 11961.162718609961, 12053.276803391824, 10838.0, 10848.186386021238, 10910.0, 10975.341479511255, 11072.391018493738, 11017.504212310305, 11022.669319920415, 11085.667429977113, 11294.548218071184, 11321.981021707756, 10458.63394781179, 10592.523336449724, 10740.62407815754, 10878.728031799268, 10940.398278094463, 7907.2488113629115, 9194.678186263449, 9223.44659320443, 9345.252379809463, 9534.332596718163, 7994.84010319802, 8940.955295223483, 9229.077092290718, 9475.47884866339, 9545.140937315675], "value": 6597.168459385232, "unit": "score"}], "name": "dromaeo_css", "value": 7202.169563597119, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 159.0, 164.0, 112.0, 111.0, 118.0, 112.0, 113.0, 111.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 120.0, 144.0, 170.0, 120.0, 145.0, 140.0, 121.0, 171.0, 119.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 190.0, 188.0, 186.0, 186.0, 187.0, 186.0, 185.0, 182.0, 185.0], "value": 186.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 91.0, 93.0, 56.0, 59.0, 58.0, 80.0, 84.0, 56.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 138.0, 75.0, 79.0, 81.0, 125.0, 77.0, 88.0, 161.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 89.0, 86.0, 88.0, 85.0, 87.0, 88.0, 88.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 87.0, 83.0, 82.0, 84.0, 85.0, 85.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 58.0, 58.0, 57.0, 57.0, 56.0, 77.0, 59.0, 56.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 72.0, 78.0, 76.0, 75.0, 71.0, 69.0, 66.0, 69.0, 95.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 98.0, 98.0, 82.0, 85.0, 81.0, 81.0, 80.0, 83.0, 80.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 139.0, 136.0, 134.0, 140.0, 141.0, 158.0, 192.0, 141.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 167.0, 168.0, 165.0, 168.0, 178.0, 176.0, 168.0, 172.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 66.0, 69.0, 67.0, 68.0, 69.0, 69.0, 65.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1490.9, "alertThreshold": 2.0}]} 16:23:02 INFO - Return code: 0 16:23:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:23:02 ERROR - # TBPL SUCCESS # 16:23:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:23:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:23:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:23:02 INFO - 2016-04-20 16:23:02.756 screenresolution[3082:50058] starting screenresolution argv=screenresolution get 16:23:02 INFO - 2016-04-20 16:23:02.758 screenresolution[3082:50058] Display 0: 1600x1200x32@60 16:23:02 INFO - 2016-04-20 16:23:02.764 screenresolution[3083:50061] starting screenresolution argv=screenresolution list 16:23:02 INFO - Available Modes on Display 0 16:23:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:23:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:23:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:23:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:23:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:23:02 INFO - Intel Iris: 16:23:02 INFO - Chipset Model: Intel Iris 16:23:02 INFO - Type: GPU 16:23:02 INFO - Bus: Built-In 16:23:02 INFO - VRAM (Dynamic, Max): 1536 MB 16:23:02 INFO - Vendor: Intel (0x8086) 16:23:02 INFO - Device ID: 0x0a2e 16:23:02 INFO - Revision ID: 0x0009 16:23:02 INFO - Displays: 16:23:02 INFO - Display: 16:23:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:23:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:23:02 INFO - Display Serial Number: 1600x1200 60 16:23:02 INFO - Main Display: Yes 16:23:02 INFO - Mirror: Off 16:23:02 INFO - Online: Yes 16:23:02 INFO - Rotation: Supported 16:23:03 INFO - Return code: 0 16:23:03 INFO - Running post-action listener: _resource_record_post_action 16:23:03 INFO - Running post-run listener: _resource_record_post_run 16:23:04 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 0; Write bytes: 695051776; Read time: 0; Write time: 1925 16:23:04 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 377653760; Write bytes: 371983872; Read time: 28153; Write time: 711 16:23:04 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 0; Write bytes: 297865216; Read time: 0; Write time: 1164 16:23:04 INFO - Running post-run listener: _upload_blobber_files 16:23:04 INFO - Blob upload gear active. 16:23:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:23:04 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 16:23:04 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:23:04 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:05 INFO - (blobuploader) - INFO - Done attempting. 16:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:06 INFO - (blobuploader) - INFO - Done attempting. 16:23:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:23:06 INFO - Return code: 0 16:23:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:23:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:23:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f1b5adb178bafa61cad8f59ec2d986878bbd9d86401156070015453c5779fca967fc1fe4bbb9cee554e85a8320b7793029f17cc1bc9c8837379bfae8b62b975", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31fee3a8898e82d24aa381cf067207fa8b151a1558572012e1aa848728dc1f01c1a744866aeff2252738ddbd49a937f87149c727b6d566b52facc03821a082c0"} 16:23:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:23:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:23:06 INFO - Contents: 16:23:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f1b5adb178bafa61cad8f59ec2d986878bbd9d86401156070015453c5779fca967fc1fe4bbb9cee554e85a8320b7793029f17cc1bc9c8837379bfae8b62b975", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31fee3a8898e82d24aa381cf067207fa8b151a1558572012e1aa848728dc1f01c1a744866aeff2252738ddbd49a937f87149c727b6d566b52facc03821a082c0"} 16:23:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:23:06 INFO - Copying logs to upload dir... 16:23:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:23:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.765414 ========= master_lag: 7.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-20 16:23:13.953252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-20 16:23:13.957048) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f1b5adb178bafa61cad8f59ec2d986878bbd9d86401156070015453c5779fca967fc1fe4bbb9cee554e85a8320b7793029f17cc1bc9c8837379bfae8b62b975", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31fee3a8898e82d24aa381cf067207fa8b151a1558572012e1aa848728dc1f01c1a744866aeff2252738ddbd49a937f87149c727b6d566b52facc03821a082c0"} build_url:https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/ZLCb1g6PTJe5nx1aigMIBA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f1b5adb178bafa61cad8f59ec2d986878bbd9d86401156070015453c5779fca967fc1fe4bbb9cee554e85a8320b7793029f17cc1bc9c8837379bfae8b62b975", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31fee3a8898e82d24aa381cf067207fa8b151a1558572012e1aa848728dc1f01c1a744866aeff2252738ddbd49a937f87149c727b6d566b52facc03821a082c0"}' ========= master_lag: 4.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-20 16:23:18.248338) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-04-20 16:23:18.248634) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rvbhMF4dJY/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kp9DXEt7FE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005460 ========= master_lag: 18.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-04-20 16:23:36.519350) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-20 16:23:36.519644) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-20 16:23:37.706937) ========= ========= Total master_lag: 41.34 =========