builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0071 starttime: 1464305054.29 results: success (0) buildid: 20160526153146 builduid: e5780b4d185f4cefb90048e26ee4322f revision: ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.287039) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.287570) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.287892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.387141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.387592) ========= 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.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-05-26 16:24:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 34.4M=0s 2016-05-26 16:24:14 (34.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.518724 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.926520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.926844) ========= 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.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.019859 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:14.976694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 16:24:14.977331) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 --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 ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-05-26 16:24:15,050 truncating revision to first 12 chars 2016-05-26 16:24:15,050 Setting DEBUG logging. 2016-05-26 16:24:15,050 attempt 1/10 2016-05-26 16:24:15,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ed8629e891d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 16:24:15,897 unpacking tar archive at: fx-team-ed8629e891d9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.090840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 16:24:16.088568) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:16.088879) ========= script_repo_revision: ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:16.089273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:16.089541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 16:24:16.105602) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-26 16:24:16.105982) ========= /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.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:24:16 INFO - MultiFileLogger online at 20160526 16:24:16 in /builds/slave/test 16:24:16 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:24:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:24:16 INFO - {'append_to_log': False, 16:24:16 INFO - 'base_work_dir': '/builds/slave/test', 16:24:16 INFO - 'blob_upload_branch': 'Fx-Team', 16:24:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:24:16 INFO - 'branch': 'Fx-Team', 16:24:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:24:16 INFO - 'config_files': ('talos/mac_config.py',), 16:24:16 INFO - 'default_actions': ('clobber', 16:24:16 INFO - 'read-buildbot-config', 16:24:16 INFO - 'download-and-extract', 16:24:16 INFO - 'populate-webroot', 16:24:16 INFO - 'create-virtualenv', 16:24:16 INFO - 'install', 16:24:16 INFO - 'run-tests'), 16:24:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:24:16 INFO - 'download_minidump_stackwalk': True, 16:24:16 INFO - 'download_symbols': 'ondemand', 16:24:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:24:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:24:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:24:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:24:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:24:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:24:16 INFO - 'installer_path': 'installer.exe', 16:24:16 INFO - 'log_level': 'info', 16:24:16 INFO - 'log_name': 'talos', 16:24:16 INFO - 'log_to_console': True, 16:24:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:24:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:24:16 INFO - 'opt_config_files': (), 16:24:16 INFO - 'pip_index': False, 16:24:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:24:16 INFO - 'cmd': ('bash', 16:24:16 INFO - '-c', 16:24:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:24:16 INFO - 'enabled': True, 16:24:16 INFO - 'halt_on_failure': False, 16:24:16 INFO - 'name': 'check_screen_resolution'},), 16:24:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:24:16 INFO - 'cmd': ('bash', 16:24:16 INFO - '-c', 16:24:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:24:16 INFO - 'enabled': True, 16:24:16 INFO - 'halt_on_failure': False, 16:24:16 INFO - 'name': 'check_screen_resolution'},), 16:24:16 INFO - 'run_cmd_checks_enabled': True, 16:24:16 INFO - 'sps_profile': False, 16:24:16 INFO - 'sps_profile_interval': 0, 16:24:16 INFO - 'suite': 'dromaeojs', 16:24:16 INFO - 'system_bits': '32', 16:24:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:24:16 INFO - 'title': 't-yosemite-r7-0071', 16:24:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:24:16 INFO - 'use_talos_json': True, 16:24:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:24:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:24:16 INFO - 'work_dir': 'build'} 16:24:16 INFO - ##### 16:24:16 INFO - ##### Running clobber step. 16:24:16 INFO - ##### 16:24:16 INFO - Running pre-action listener: _resource_record_pre_action 16:24:16 INFO - Running main action method: clobber 16:24:16 INFO - rmtree: /builds/slave/test/build 16:24:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:24:17 INFO - Running post-action listener: _resource_record_post_action 16:24:17 INFO - ##### 16:24:17 INFO - ##### Finished clobber step (success) 16:24:17 INFO - ##### 16:24:17 INFO - ##### 16:24:17 INFO - ##### Running read-buildbot-config step. 16:24:17 INFO - ##### 16:24:17 INFO - Running pre-action listener: _resource_record_pre_action 16:24:17 INFO - Running main action method: read_buildbot_config 16:24:17 INFO - Using buildbot properties: 16:24:17 INFO - { 16:24:17 INFO - "project": "", 16:24:17 INFO - "product": "firefox", 16:24:17 INFO - "script_repo_revision": "production", 16:24:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:24:17 INFO - "repository": "", 16:24:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:24:17 INFO - "buildid": "20160526153146", 16:24:17 INFO - "pgo_build": "False", 16:24:17 INFO - "basedir": "/builds/slave/test", 16:24:17 INFO - "buildnumber": 38, 16:24:17 INFO - "slavename": "t-yosemite-r7-0071", 16:24:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:24:17 INFO - "platform": "yosemite_r7", 16:24:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:24:17 INFO - "branch": "fx-team", 16:24:17 INFO - "repo_path": "integration/fx-team", 16:24:17 INFO - "revision": "ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5", 16:24:17 INFO - "stage_platform": "macosx64", 16:24:17 INFO - "builduid": "e5780b4d185f4cefb90048e26ee4322f", 16:24:17 INFO - "slavebuilddir": "test" 16:24:17 INFO - } 16:24:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:24:17 INFO - Running post-action listener: _resource_record_post_action 16:24:17 INFO - ##### 16:24:17 INFO - ##### Finished read-buildbot-config step (success) 16:24:17 INFO - ##### 16:24:17 INFO - ##### 16:24:17 INFO - ##### Running download-and-extract step. 16:24:17 INFO - ##### 16:24:17 INFO - Running pre-action listener: _resource_record_pre_action 16:24:17 INFO - Running main action method: download_and_extract 16:24:17 INFO - mkdir: /builds/slave/test/build/tests 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:17 INFO - https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:24:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:24:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:24:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:24:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:24:37 INFO - Downloaded 1199 bytes. 16:24:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:24:37 INFO - Using the following test package requirements: 16:24:37 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:24:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:24:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:24:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'jsshell-mac.zip'], 16:24:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:24:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:24:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:24:37 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:24:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:24:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:24:37 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:24:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:24: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:24:37 INFO - https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:24:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:24:39 INFO - Downloaded 18622032 bytes. 16:24:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:24:42 INFO - Return code: 0 16:24:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:24:42 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:24:42 INFO - https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:24:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:24:44 INFO - Downloaded 18622032 bytes. 16:24:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:24:47 INFO - Return code: 0 16:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:47 INFO - https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:24:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:24:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:24:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:24:48 INFO - Downloaded 10834034 bytes. 16:24:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:24:48 INFO - Return code: 0 16:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:48 INFO - https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:24:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:24:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:24:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:24:51 INFO - Downloaded 110431893 bytes. 16:24:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:24:51 INFO - mkdir: /builds/slave/test/properties 16:24:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:51 INFO - Contents: 16:24:51 INFO - build_url:https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:24:52 INFO - Running post-action listener: _resource_record_post_action 16:24:52 INFO - Running post-action listener: set_extra_try_arguments 16:24:52 INFO - ##### 16:24:52 INFO - ##### Finished download-and-extract step (success) 16:24:52 INFO - ##### 16:24:52 INFO - ##### 16:24:52 INFO - ##### Running populate-webroot step. 16:24:52 INFO - ##### 16:24:52 INFO - Running pre-action listener: _resource_record_pre_action 16:24:52 INFO - Running main action method: populate_webroot 16:24:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:24:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:24:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:24:52 INFO - '--tppagecycles', 16:24:52 INFO - '7'], 16:24:52 INFO - 'tests': ['tsvgm']}}, 16:24:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:24:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:24:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['damp', 'tps']}, 16:24:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'tests': ['damp', 'tps']}, 16:24:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['dromaeo_dom']}, 16:24:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['a11yr', 16:24:52 INFO - 'ts_paint', 16:24:52 INFO - 'tpaint', 16:24:52 INFO - 'sessionrestore', 16:24:52 INFO - 'sessionrestore_no_auto_restore', 16:24:52 INFO - 'tabpaint']}, 16:24:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['tsvgx', 16:24:52 INFO - 'tsvgr_opacity', 16:24:52 INFO - 'tart', 16:24:52 INFO - 'tscrollx', 16:24:52 INFO - 'cart']}, 16:24:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:52 INFO - 'tsvgr_opacity', 16:24:52 INFO - 'tart', 16:24:52 INFO - 'tscrollx', 16:24:52 INFO - 'cart']}, 16:24:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'talos_options': ['--disable-e10s'], 16:24:52 INFO - 'tests': ['tp5o']}, 16:24:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'tests': ['tp5o']}, 16:24:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'talos_options': ['--disable-e10s', 16:24:52 INFO - '--xperf_path', 16:24:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:52 INFO - 'tests': ['tp5n']}, 16:24:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:24:52 INFO - 'talos_options': ['--xperf_path', 16:24:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:52 INFO - 'tests': ['tp5n']}}, 16:24:52 INFO - 'talos.zip': {'path': '', 16:24:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:24:52 INFO - Running post-action listener: _resource_record_post_action 16:24:52 INFO - ##### 16:24:52 INFO - ##### Finished populate-webroot step (success) 16:24:52 INFO - ##### 16:24:52 INFO - ##### 16:24:52 INFO - ##### Running create-virtualenv step. 16:24:52 INFO - ##### 16:24:52 INFO - Running pre-action listener: _resource_record_pre_action 16:24:52 INFO - Running main action method: create_virtualenv 16:24:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:52 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:24:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:24:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:52 INFO - Using real prefix '/tools/python27' 16:24:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:56 INFO - Installing pip.................done. 16:24:56 INFO - Return code: 0 16:24:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:56 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:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:56 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:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:56 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:24:56 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:24:56 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:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:56 INFO - 'HOME': '/Users/cltbld', 16:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:56 INFO - 'LOGNAME': 'cltbld', 16:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:56 INFO - 'MOZ_NO_REMOTE': '1', 16:24:56 INFO - 'NO_EM_RESTART': '1', 16:24:56 INFO - 'PAGER': '/bin/cat', 16:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:56 INFO - 'PWD': '/builds/slave/test', 16:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:56 INFO - 'SHELL': '/bin/bash', 16:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:24:56 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:56 INFO - 'USER': 'cltbld', 16:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:56 INFO - 'XPC_FLAGS': '0x0', 16:24:56 INFO - 'XPC_SERVICE_NAME': '0', 16:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:56 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:24:56 INFO - Cleaning up... 16:24:56 INFO - Return code: 0 16:24:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:56 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:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:56 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:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:56 INFO - 'HOME': '/Users/cltbld', 16:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:56 INFO - 'LOGNAME': 'cltbld', 16:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:56 INFO - 'MOZ_NO_REMOTE': '1', 16:24:56 INFO - 'NO_EM_RESTART': '1', 16:24:56 INFO - 'PAGER': '/bin/cat', 16:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:56 INFO - 'PWD': '/builds/slave/test', 16:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:56 INFO - 'SHELL': '/bin/bash', 16:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:24:56 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:56 INFO - 'USER': 'cltbld', 16:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:56 INFO - 'XPC_FLAGS': '0x0', 16:24:56 INFO - 'XPC_SERVICE_NAME': '0', 16:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:56 INFO - Downloading/unpacking psutil>=3.1.1 16:24:56 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:24:56 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:24:56 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:24:56 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:24:56 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:24:56 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:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:59 INFO - Installing collected packages: psutil 16:24:59 INFO - Running setup.py install for psutil 16:25:00 INFO - building 'psutil._psutil_osx' extension 16:25:00 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:25:00 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:25:00 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:25:00 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:25:00 INFO - building 'psutil._psutil_posix' extension 16:25:00 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:25:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:25:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:25:00 INFO - ^ 16:25:00 INFO - 1 warning generated. 16:25:00 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:25:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:25:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:25:00 INFO - Successfully installed psutil 16:25:00 INFO - Cleaning up... 16:25:00 INFO - Return code: 0 16:25:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:25:00 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:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:00 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:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:00 INFO - 'HOME': '/Users/cltbld', 16:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:00 INFO - 'LOGNAME': 'cltbld', 16:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:00 INFO - 'MOZ_NO_REMOTE': '1', 16:25:00 INFO - 'NO_EM_RESTART': '1', 16:25:00 INFO - 'PAGER': '/bin/cat', 16:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:00 INFO - 'PWD': '/builds/slave/test', 16:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:00 INFO - 'SHELL': '/bin/bash', 16:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:00 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:00 INFO - 'USER': 'cltbld', 16:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:00 INFO - 'XPC_FLAGS': '0x0', 16:25:00 INFO - 'XPC_SERVICE_NAME': '0', 16:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:25:01 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:25:01 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:25:01 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:25:01 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:25:01 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:25:01 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:25:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:25:03 INFO - Installing collected packages: mozsystemmonitor 16:25:03 INFO - Running setup.py install for mozsystemmonitor 16:25:03 INFO - Successfully installed mozsystemmonitor 16:25:03 INFO - Cleaning up... 16:25:03 INFO - Return code: 0 16:25:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:03 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:03 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:25:03 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:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:03 INFO - 'HOME': '/Users/cltbld', 16:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:03 INFO - 'LOGNAME': 'cltbld', 16:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:03 INFO - 'MOZ_NO_REMOTE': '1', 16:25:03 INFO - 'NO_EM_RESTART': '1', 16:25:03 INFO - 'PAGER': '/bin/cat', 16:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:03 INFO - 'PWD': '/builds/slave/test', 16:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:03 INFO - 'SHELL': '/bin/bash', 16:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:03 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:03 INFO - 'USER': 'cltbld', 16:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:03 INFO - 'XPC_FLAGS': '0x0', 16:25:03 INFO - 'XPC_SERVICE_NAME': '0', 16:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:25:04 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:25:04 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:25:04 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:25:04 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:25:04 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:25:04 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:25:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:25:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:25:06 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:25:06 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:25:06 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:25:06 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:25:06 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:25:06 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:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:25:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:07 INFO - Downloading docopt-0.6.1.tar.gz 16:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:25:07 INFO - Installing collected packages: blobuploader, requests, docopt 16:25:07 INFO - Running setup.py install for blobuploader 16:25:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:25:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:25:08 INFO - Running setup.py install for requests 16:25:08 INFO - Running setup.py install for docopt 16:25:08 INFO - Successfully installed blobuploader requests docopt 16:25:08 INFO - Cleaning up... 16:25:08 INFO - Return code: 0 16:25:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:08 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:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:08 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:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:08 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:08 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:25:08 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:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:08 INFO - 'HOME': '/Users/cltbld', 16:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:08 INFO - 'LOGNAME': 'cltbld', 16:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:08 INFO - 'MOZ_NO_REMOTE': '1', 16:25:08 INFO - 'NO_EM_RESTART': '1', 16:25:08 INFO - 'PAGER': '/bin/cat', 16:25:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:08 INFO - 'PWD': '/builds/slave/test', 16:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:08 INFO - 'SHELL': '/bin/bash', 16:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:08 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:08 INFO - 'USER': 'cltbld', 16:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:08 INFO - 'XPC_FLAGS': '0x0', 16:25:08 INFO - 'XPC_SERVICE_NAME': '0', 16:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:08 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-A52NJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ZIj0XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-cVHOJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-MSdIbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-5fSLQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-9Tj70y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QQq3FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-SYW1u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-wcvcQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:09 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-n7Fqg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-g4S0HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-1QsqPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-YsTCpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Xg1s1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-NpT5q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-dciwXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:10 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-G569Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:25:10 INFO - Running setup.py install for manifestparser 16:25:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:25:10 INFO - Running setup.py install for mozcrash 16:25:11 INFO - Running setup.py install for mozdebug 16:25:11 INFO - Running setup.py install for mozdevice 16:25:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Running setup.py install for mozfile 16:25:11 INFO - Running setup.py install for mozhttpd 16:25:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Running setup.py install for mozinfo 16:25:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Running setup.py install for mozInstall 16:25:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:25:11 INFO - Running setup.py install for mozleak 16:25:12 INFO - Running setup.py install for mozlog 16:25:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Running setup.py install for moznetwork 16:25:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Running setup.py install for mozprocess 16:25:12 INFO - Running setup.py install for mozprofile 16:25:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Running setup.py install for mozrunner 16:25:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:25:12 INFO - Running setup.py install for mozscreenshot 16:25:13 INFO - Running setup.py install for moztest 16:25:13 INFO - Running setup.py install for mozversion 16:25:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:25:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:25:13 INFO - Cleaning up... 16:25:13 INFO - Return code: 0 16:25:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:13 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:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:13 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:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:13 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:13 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:25:13 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:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:13 INFO - 'HOME': '/Users/cltbld', 16:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:13 INFO - 'LOGNAME': 'cltbld', 16:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:13 INFO - 'MOZ_NO_REMOTE': '1', 16:25:13 INFO - 'NO_EM_RESTART': '1', 16:25:13 INFO - 'PAGER': '/bin/cat', 16:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:13 INFO - 'PWD': '/builds/slave/test', 16:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:13 INFO - 'SHELL': '/bin/bash', 16:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:13 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:13 INFO - 'USER': 'cltbld', 16:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:13 INFO - 'XPC_FLAGS': '0x0', 16:25:13 INFO - 'XPC_SERVICE_NAME': '0', 16:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:13 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-eQGVe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:13 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:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:13 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-g43oiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:13 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:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:13 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-coLBxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:13 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:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:13 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-qQdP0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-_Ox90F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0mneSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-SEPFyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-d4gqZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-XXLVID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-J5ywjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fEj_4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-XB1kOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:14 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:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:14 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Hh_xKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:15 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:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:15 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sqay1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:15 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:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:15 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-7X96gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:15 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:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:15 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-assi2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:15 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:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:15 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sg8Hi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:15 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:25:15 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:25:15 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:25:15 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:25:15 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:25:15 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:25:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:17 INFO - Downloading blessings-1.6.tar.gz 16:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:25:17 INFO - Installing collected packages: blessings 16:25:17 INFO - Running setup.py install for blessings 16:25:18 INFO - Successfully installed blessings 16:25:18 INFO - Cleaning up... 16:25:18 INFO - Return code: 0 16:25:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:25:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:18 INFO - Reading from file tmpfile_stdout 16:25:18 INFO - Current package versions: 16:25:18 INFO - blessings == 1.6 16:25:18 INFO - blobuploader == 1.2.4 16:25:18 INFO - docopt == 0.6.1 16:25:18 INFO - manifestparser == 1.1 16:25:18 INFO - mozInstall == 1.12 16:25:18 INFO - mozcrash == 0.17 16:25:18 INFO - mozdebug == 0.1 16:25:18 INFO - mozdevice == 0.48 16:25:18 INFO - mozfile == 1.2 16:25:18 INFO - mozhttpd == 0.7 16:25:18 INFO - mozinfo == 0.9 16:25:18 INFO - mozleak == 0.1 16:25:18 INFO - mozlog == 3.2 16:25:18 INFO - moznetwork == 0.27 16:25:18 INFO - mozprocess == 0.22 16:25:18 INFO - mozprofile == 0.28 16:25:18 INFO - mozrunner == 6.11 16:25:18 INFO - mozscreenshot == 0.1 16:25:18 INFO - mozsystemmonitor == 0.3 16:25:18 INFO - moztest == 0.7 16:25:18 INFO - mozversion == 1.4 16:25:18 INFO - psutil == 3.1.1 16:25:18 INFO - requests == 1.2.3 16:25:18 INFO - wsgiref == 0.1.2 16:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:18 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:18 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:25:18 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:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:18 INFO - 'HOME': '/Users/cltbld', 16:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:18 INFO - 'LOGNAME': 'cltbld', 16:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:18 INFO - 'MOZ_NO_REMOTE': '1', 16:25:18 INFO - 'NO_EM_RESTART': '1', 16:25:18 INFO - 'PAGER': '/bin/cat', 16:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:18 INFO - 'PWD': '/builds/slave/test', 16:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:18 INFO - 'SHELL': '/bin/bash', 16:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:18 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:18 INFO - 'USER': 'cltbld', 16:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:18 INFO - 'XPC_FLAGS': '0x0', 16:25:18 INFO - 'XPC_SERVICE_NAME': '0', 16:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 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:25:18 INFO - Cleaning up... 16:25:18 INFO - Return code: 0 16:25:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:18 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:18 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:25:18 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:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:18 INFO - 'HOME': '/Users/cltbld', 16:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:18 INFO - 'LOGNAME': 'cltbld', 16:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:18 INFO - 'MOZ_NO_REMOTE': '1', 16:25:18 INFO - 'NO_EM_RESTART': '1', 16:25:18 INFO - 'PAGER': '/bin/cat', 16:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:18 INFO - 'PWD': '/builds/slave/test', 16:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:18 INFO - 'SHELL': '/bin/bash', 16:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:18 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:18 INFO - 'USER': 'cltbld', 16:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:18 INFO - 'XPC_FLAGS': '0x0', 16:25:18 INFO - 'XPC_SERVICE_NAME': '0', 16:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:19 INFO - Downloading/unpacking jsonschema 16:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:25:21 INFO - Downloading/unpacking functools32 (from jsonschema) 16:25: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:25: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:25: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:25: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:25: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:25: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:25:21 INFO - Downloading functools32-3.2.3-2.tar.gz 16:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:25:21 INFO - warning: no files found matching '*.txt' 16:25:21 INFO - no previously-included directories found matching 'build' 16:25:21 INFO - no previously-included directories found matching 'dist' 16:25:21 INFO - no previously-included directories found matching '.git*' 16:25:21 INFO - Installing collected packages: jsonschema, functools32 16:25:22 INFO - Running setup.py install for functools32 16:25:22 INFO - warning: no files found matching '*.txt' 16:25:22 INFO - no previously-included directories found matching 'build' 16:25:22 INFO - no previously-included directories found matching 'dist' 16:25:22 INFO - no previously-included directories found matching '.git*' 16:25:22 INFO - Successfully installed jsonschema functools32 16:25:22 INFO - Cleaning up... 16:25:22 INFO - Return code: 0 16:25:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:25:22 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 0x1068fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb553d78b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, '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.F7L5XwFFf0/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.3yRJnuVEek/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:25:22 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:25:22 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:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:25:22 INFO - 'HOME': '/Users/cltbld', 16:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:25:22 INFO - 'LOGNAME': 'cltbld', 16:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:22 INFO - 'MOZ_NO_REMOTE': '1', 16:25:22 INFO - 'NO_EM_RESTART': '1', 16:25:22 INFO - 'PAGER': '/bin/cat', 16:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:22 INFO - 'PWD': '/builds/slave/test', 16:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:25:22 INFO - 'SHELL': '/bin/bash', 16:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:25:22 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:25:22 INFO - 'USER': 'cltbld', 16:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:22 INFO - 'XPC_FLAGS': '0x0', 16:25:22 INFO - 'XPC_SERVICE_NAME': '0', 16:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:22 INFO - Downloading/unpacking flake8 16:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:25:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:25:24 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:25:24 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:25:24 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:25:24 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:25:24 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:25:24 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:25:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:25:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:25: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:25: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:25: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:25: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:25: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:25: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:25:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:25: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:25: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:25: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:25: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:25: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:25: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:25:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:25:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:25:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:25:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:25:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:25:26 INFO - no previously-included directories found matching 'docs/_build' 16:25:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:25:26 INFO - Running setup.py install for pep8 16:25:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:25:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:25:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:25:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:25:26 INFO - no previously-included directories found matching 'docs/_build' 16:25:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:25:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:25:26 INFO - Cleaning up... 16:25:26 INFO - Return code: 0 16:25:26 INFO - Running post-action listener: _resource_record_post_action 16:25:26 INFO - Running post-action listener: _start_resource_monitoring 16:25:26 INFO - Starting resource monitoring. 16:25:26 INFO - ##### 16:25:26 INFO - ##### Finished create-virtualenv step (success) 16:25:26 INFO - ##### 16:25:26 INFO - ##### 16:25:26 INFO - ##### Running install step. 16:25:26 INFO - ##### 16:25:26 INFO - Running pre-action listener: _resource_record_pre_action 16:25:26 INFO - Running main action method: install 16:25:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:26 INFO - Reading from file tmpfile_stdout 16:25:26 INFO - Detecting whether we're running mozinstall >=1.0... 16:25:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:25:27 INFO - Reading from file tmpfile_stdout 16:25:27 INFO - Output received: 16:25:27 INFO - Usage: mozinstall [options] installer 16:25:27 INFO - Options: 16:25:27 INFO - -h, --help show this help message and exit 16:25:27 INFO - -d DEST, --destination=DEST 16:25:27 INFO - Directory to install application into. [default: 16:25:27 INFO - "/builds/slave/test"] 16:25:27 INFO - --app=APP Application being installed. [default: firefox] 16:25:27 INFO - mkdir: /builds/slave/test/build/application 16:25:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:26:01 INFO - Reading from file tmpfile_stdout 16:26:01 INFO - Output received: 16:26:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:26:01 INFO - Running post-action listener: _resource_record_post_action 16:26:01 INFO - ##### 16:26:01 INFO - ##### Finished install step (success) 16:26:01 INFO - ##### 16:26:01 INFO - ##### 16:26:01 INFO - ##### Running run-tests step. 16:26:01 INFO - ##### 16:26:01 INFO - Running pre-action listener: _resource_record_pre_action 16:26:01 INFO - Running main action method: run_tests 16:26:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:26:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:26:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:26:01 INFO - 2016-05-26 16:26:01.031 screenresolution[1909:10484] starting screenresolution argv=screenresolution get 16:26:01 INFO - 2016-05-26 16:26:01.034 screenresolution[1909:10484] Display 0: 1600x1200x32@60 16:26:01 INFO - 2016-05-26 16:26:01.040 screenresolution[1910:10486] starting screenresolution argv=screenresolution list 16:26:01 INFO - Available Modes on Display 0 16:26:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:26:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:26:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:26:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:26:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:26:01 INFO - Intel Iris: 16:26:01 INFO - Chipset Model: Intel Iris 16:26:01 INFO - Type: GPU 16:26:01 INFO - Bus: Built-In 16:26:01 INFO - VRAM (Dynamic, Max): 1536 MB 16:26:01 INFO - Vendor: Intel (0x8086) 16:26:01 INFO - Device ID: 0x0a2e 16:26:01 INFO - Revision ID: 0x0009 16:26:01 INFO - Displays: 16:26:01 INFO - Display: 16:26:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:26:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:26:01 INFO - Display Serial Number: 1600x1200 60 16:26:01 INFO - Main Display: Yes 16:26:01 INFO - Mirror: Off 16:26:01 INFO - Online: Yes 16:26:01 INFO - Rotation: Supported 16:26:01 INFO - Return code: 0 16:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:26:01 INFO - Python 2.7.3 16:26:01 INFO - Return code: 0 16:26:01 INFO - grabbing minidump binary from tooltool 16:26:01 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:26:01 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a2d30>, '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 0x1065c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106799b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:26:01 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:26:01 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:26:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:26:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:26:01 INFO - Return code: 0 16:26:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:26:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:26:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:26:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:26:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:26:02 INFO - Return code: 0 16:26:02 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/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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:26:02 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/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7L5XwFFf0/Render', 16:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:02 INFO - 'HOME': '/Users/cltbld', 16:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:02 INFO - 'LOGNAME': 'cltbld', 16:26:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:26:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:02 INFO - 'MOZ_NO_REMOTE': '1', 16:26:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:26:02 INFO - 'NO_EM_RESTART': '1', 16:26:02 INFO - 'PAGER': '/bin/cat', 16:26:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:02 INFO - 'PWD': '/builds/slave/test', 16:26:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:02 INFO - 'SHELL': '/bin/bash', 16:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yRJnuVEek/Listeners', 16:26:02 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 16:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:02 INFO - 'USER': 'cltbld', 16:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:02 INFO - 'XPC_FLAGS': '0x0', 16:26:02 INFO - 'XPC_SERVICE_NAME': '0', 16:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:02 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/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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:26:02 INFO - mozversion application_buildid: 20160526153146 16:26:02 INFO - mozversion application_changeset: ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 16:26:02 INFO - mozversion application_display_name: Nightly 16:26:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:26:02 INFO - mozversion application_name: Firefox 16:26:02 INFO - mozversion application_remotingname: firefox 16:26:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:26:02 INFO - mozversion application_vendor: Mozilla 16:26:02 INFO - mozversion application_version: 49.0a1 16:26:02 INFO - mozversion platform_buildid: 20160526153146 16:26:02 INFO - mozversion platform_changeset: ed8629e891d9e655af2e1e79fe8c32e8cbcf44b5 16:26:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:26:02 INFO - mozversion platform_version: 49.0a1 16:26:02 INFO - using testdate: 1464305162 16:26:02 INFO - actual date: 1464305162 16:26:02 INFO - starting webserver on 'localhost:49710' 16:26:02 INFO - SUITE-START | Running 2 tests 16:26:02 INFO - TEST-START | dromaeo_css 16:26:02 INFO - operating with platform_type : mac_ 16:26:02 INFO - Initialising browser for dromaeo_css test... 16:26:02 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpL779SJ/profile http://localhost:49710/getInfo.html) 16:26:04 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 16:26:04 INFO - PROCESS | 1928 | colorDepth:24 16:26:04 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 16:26:04 INFO - PROCESS | 1928 | __metrics 16:26:05 INFO - PROCESS | 1928 | 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:26:05 ERROR - PROCESS | 1928 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:26:05 INFO - TEST-INFO | 1928: exit 0 16:26:05 INFO - Browser initialized. 16:26:05 INFO - Running cycle 1/1 for dromaeo_css test... 16:26:05 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpL779SJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:26:17 INFO - PROCESS | 1930 | RSS: Main: 241291264 16:26:17 INFO - PROCESS | 1930 | 16:27:09 INFO - PROCESS | 1930 | 1464305229210 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:29:19 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49710/tests/dromaeo/cssquery-ext.html) 16:29:20 INFO - PROCESS | 1930 | RSS: Main: 363651072 16:29:20 INFO - PROCESS | 1930 | 16:32:24 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-ext.html (next: http://localhost:49710/tests/dromaeo/cssquery-jquery.html) 16:32:24 INFO - PROCESS | 1930 | RSS: Main: 374546432 16:32:24 INFO - PROCESS | 1930 | 16:35:26 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49710/tests/dromaeo/cssquery-mootools.html) 16:35:27 INFO - PROCESS | 1930 | RSS: Main: 371306496 16:35:27 INFO - PROCESS | 1930 | 16:38:29 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49710/tests/dromaeo/cssquery-prototype.html) 16:38:30 INFO - PROCESS | 1930 | RSS: Main: 369938432 16:38:30 INFO - PROCESS | 1930 | 16:41:33 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49710/tests/dromaeo/cssquery-yui.html) 16:41:34 INFO - PROCESS | 1930 | RSS: Main: 345493504 16:41:34 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-yui.html (next: http://localhost:49710/tests/dromaeo/cssquery-dojo.html) 16:44:37 INFO - PROCESS | 1930 | RSS: Main: 374722560 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | __start_tp_report 16:44:37 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 16:44:37 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 16:44:37 INFO - PROCESS | 1930 | |i|pagename|runs| 16:44:37 INFO - PROCESS | 1930 | |0;dojo.html;3393.8981830545085;3405.267867408505;3423.760336776426;3444.517767512547;3477.9304413911705;5526.889462210754;5569.749361278742;5578.135389014704;5698.059820129679;5721.6853073080965;5584.4136365681525;5595.860103497404;5670.886582268352;5715.885682286352;5749.022666146755;5000.574951129159;5039.31969184161;5109.718965456898;5130.6152038597065;5214.4524824893215;5173.663711858736;5185.948140518603;5186.507281808241;5236.659617124894;5264.657797243167;5038.445770965191;5090.77091530882;5092.694438333712;5141.511556402136;5171.405288391846;5161.4322424533275;5164.948350516484;5167.870803229912;5169.276301317818;5169.689818610896;5071.822486213002;5113.616478764107;5120.411152717448;5136.43499215086;5142.717150556718;5529.585281103933;5553.139263414178;5561.082421400464;5561.249231353756;5567.192757050246;6043.154037871512;6060.81685382091;6077.221614090946;6134.9079763803575;6135.417135372132;6131.233595800525;6238.781642642532;6253.249610046823;6254.530910181755;6260.780872669435;5754.712264386764;5792.9283082629845;5809.9832529307205;5820.272465941757;5823.79616713413;5275.947240527622;6206.689665516707;6242.563020588606;6281.214848143982;6312.210973628296;5079.8984020319385;6081.610060885959;6087.633962904846;6120.392997855451;6122.785702500391;6067.271927368744;6259.374062593795;6263.154474145932;6264.060390941304;6283.59156654816;6053.939460605426;6071.967765479879;6115.51416190291;6134.228656665725;6143.170671959318;6088.6042407243385;6157.199564056644;6194.969025154845;6200.193974783249;6210.285817130997;4191.371294305817;4858.6356023298395;4860.295257187688;4873.26900964851;4910.656254062253;4303.289957157097;4304.311310190355;4371;4371.672124590668;4424.646028317727;5642.379338272869;5681.062624666966;5688.857778555486;5691.544676425876;5693.629914055573;6119.510439164945;6291.370862913672;6316.431521163117;6319.431251187415;6331.96834015827;5581.665100094007;5602.887942241132;5630.521405680481;5677.205191273225;5685.317761868602;5289.365276166807;5306.92039619406;5318.042752304622;5322.813701520428;5325.4940780625775;6255.343188965093;6309.968450157719;6317.305096439388;6334.049892515975;6448.709808058648;6147.108669243024;6212.409821066992;6216.222972128484;6288.717007734755;6290.874182516232;6295.087212354275;6323.841903952438;6325.746970121144;6328.968355158195;6331;5934.139549765347;6030.48740857032;6174.073888916699;6174.413430724074;6197.783077592263;6109.526129009804;6206.844828879314;6247.09417134522;6256.468200203034;6262.530310226843;5897.823065308047;5987.491063259672;6004.039353703386;6036.667983260787;6065.332813390612;5796.246487956539;5835.124731290341;5877.911831322448;5969.283685957713;6078.361772014052;5821.446962538552;5827.562932779973;5834.679092649775;5857.26784151981;5870.471657550841;5943.138244954544;5950.404959504015;5957.91063134062;5992.0712289595185;5995.61028533144;5631.267935168402;5639.661620302795;5695.373508913934;5772.278465191851;5811.157382179645 16:44:37 INFO - PROCESS | 1930 | |1;ext.html;475.0617530377507;494.0810093226598;494.09580467744007;500.7496251874063;512.8767998084196;31090.37819243614;32877.835610821945;35680;42103.736887893414;43606.563934360725;45099.54900450994;45339.77330113345;45436.0912781745;45495.77252113735;45506.54493455064;9358.532073396336;9579.137877591016;9581.137697607213;9584.520773961274;9586.712398628053;8503.319734421262;8736.126387361244;9453.149216570508;11616.186866919319;11628.069754419626;11658.708532286675;11749.236299640539;11751.353675547842;11768.7646247075;11774.116941229398;8463.111373305775;8667.133286671282;11691.590794322201;11793.997510211642;11809.645710628694;11382.658520244406;11799.94100029503;11812.822807657893;11827.586034488751;11829.645110646694;41500.37749433761;41923.58076419227;41928;41951.580484195074;42054.948626284284;3111.23774675206;3163.794353367047;3281.1469018055477;3316.403047451482;3458.0317511096932;28994.565081523793;29097.854510727524;29587.70412295871;29599.852000740073;29619.851900740574;2679.973200267992;2699.1362763915554;2707.323169207698;2754.380264440505;2801.6217810595513;482244.9999999965;491693;493347;494868;497509;63492.68253658795;64758.028629570596;64862.675686622206;64916;65209.02186467207;35292.82353588267;35762.64237357593;35783.4632480513;36937.63062369342;36978.815105924295;16655.3337866484;16790.16049197535;16852.325906963582;16869.578260543585;16905.07022113771;11623.76752464946;11900.940495297642;12586.685332866751;12795.976321894226;12909.031822613342;19915.502112447306;20555.79444205569;20598.38204853857;20611.38165855027;20652.483687907923;8141.755747327589;8144.959275203586;8153.959230203811;8187.877181842277;8218.301444377175;901.3600343755876;939.572494514994;956.9808154315771;976.799756050008;1010.7725761703705;29768;29878.701212988028;30017.699823001927;30799;30904.381912362078;1107.5514416661265;1109.5118148014847;1125.2348403085816;1157.9826302605468;1175.7766024455327;15363.61590960235;15417.691646167239;15424;15806.130662813655;15848.68302633941;187445.06277468524;187597;188420.05789970962;188712;188968;61695.6915215421;62225.37774622286;62238.37761622326;62324.75350492965;62366.68816655978;1302.5571305755898;1430.6995530938411;1454.810874586297;1469.941202351894;1473.852614738539;30819.69180308258;30835;30949;31592.684073158973;31715.84142079275;53065.469345307596;56568.7171564156;60687.089693655515;60755.99999999823;64405.67797160984;605536;611816;614132;617574;617701;35805.10487237736;41664.58335416607;42014.57985420229;42918;43778;47323.053538929715;47395.52604473911;47754.761226193645;49078.50921490882;49262.26106608476;37720.43419348657;38875.805620972846;39188.41217381685;39686;40152.79923600363;37760;38446;38568.035799105244;39602.801985989885;39621;37994.0501487465;38265;38656;39231.41152882766;41224;32103.678963211005;34293.657063430044;34860.65139348574;35209.119772005906;35402.46896296609;25155.87422062878;25200.369990749645;25354.366140846625;25477.643392890554;25672.871635641703 16:44:37 INFO - PROCESS | 1930 | |2;jquery.html;377.06675976957024;377.1702255038914;385.4853770216761;398.7089424719953;401.71879684221034;19195.520111997208;20666.27668031619;21279.148834046657;21574.1370345186;21574.352769416924;20248.797512024874;20668.27661031864;21143.577128457422;21348.932553372346;21367.03848326825;5261.842144735664;5668.773249070043;5908.291005079375;6087.543434242428;6092.5125989920925;5954.166416701665;5966.284045914496;5992.5505587080925;6051.273847138327;6059.303180134297;5748;5876.5298776098025;5909.586328956974;5912.260967379078;5930.792422265222;5793.855153621151;5834.4165583441745;5867.1492633568105;5875.412458754133;5879.52963762898;5035.446100928895;5175.301334319856;5195.350581177353;5205.583553315726;5210.505002024839;25571;25697.48605027889;25825.354366140997;26139.869300653565;26155.476890462083;1839.3286450445491;2110.525131845338;2155.3749412670463;2362.9527409451716;2436.8294219404647;8127.715529956423;9670.274729395253;9911.207103431709;9925.454100024495;9983.600655973825;1720.6214632780768;1788.2131861980818;1834.1654547181133;1838.2371315904018;1845.1604519943394;87070.56464717699;99626.5018674902;104070.95929040688;129263;129421.05868411981;11433.14251431146;11559.595414160633;11681.88318116825;11683.123765717604;11813.586524471773;14695.632609184855;14834.554963351116;14838.406463741545;14842.257887105601;14845.257737113101;8477.448965817202;8649.524276164871;8678.39251252419;8755;8768.298536117096;8526.65893364271;8540.359473039547;8543.35924805642;8662.87005694915;8686.826263474695;3747.9625203747614;4394.011347446799;4504.414426124583;4590.586847183769;4657.068586282757;1620.9675806483804;1699.354245386745;1937.2541571495076;1939.786623471417;1942.5046613113566;816.9706170225439;872.4808738800403;911.2664305234217;919.0258326174277;999.5851721535481;20040;20228.29200977988;20262.088206030763;20397.38807835767;20506.077226524543;912.8862787399362;927.3740225347864;949.2026697573933;1014.523174108168;1037.5382954585136;10154.847677284846;10507.369557826554;10744.516496757664;10749.355038697704;10784.730381740519;587546.0622696945;596194;596630;597198;597598.0120099372;77022.2297777015;77064;85363.99999999876;134791;139324;990.8117457683018;992.533509250651;1006.5017816181037;1039.6153423233452;1041.6458404142477;17168.141592920554;17516.474505764847;17564.858284211485;17674.823251767317;17795.37716179979;53814.73092634512;53990;54468;55863.41365863381;56014;49610;70487;70523.64738176276;77848;83613.58193208995;7545.962270188614;7751.689932402641;7811.843763124592;7864.9606751965875;8090.1909809018625;8559.957200214209;9287;9893.50532473359;10976.945115274373;11147.55409783632;10274.948625256826;10367.896321036995;10460.790784184426;10589.470526473491;10724.088452481314;8276.337892968668;10225;10356.223283253938;10494.160467162761;10534.104601108991;10467.94766026165;10477.947610262205;10536.736581585521;10662.147028237878;10806.405647689138;7702.961485192538;7797.688092476237;7881.802954926173;7922.841543169219;8188.222118898695;9133.817323653622;10056.396616203052;10331.535080921227;10667.199960003187;10922.726931826768 16:44:37 INFO - PROCESS | 1930 | |3;mootools.html;1384.2663388404146;1395.4487977248994;1500.7448733715266;1542.3213785934186;1954.8045195480445;7753.030871141107;8461.915380846189;8748.212660860521;8768.824623507526;8906.955465222682;8477.279431248351;8723.171298726627;8765.991910930245;8780.824383512341;8782.121787821216;1968.370121561101;2376.8573885566834;2380.1193558383334;2381.392744850061;2381.416552944531;2321.849079809807;2354.1760383865594;2380.297812145421;2383.5113801670705;2383.6067048936907;2314.6875171851752;2318;2324.9651255231183;2347.3310106619615;2351.0948284910346;2309.1340747219792;2336.334144768741;2341.672165896776;2346.835721499496;2356.9410764730846;2341.789238968501;2343.332150337146;2350.189184731265;2351.541449417364;2355.9411014724596;8463.703770368069;8652.264557512619;8715;8717.825643487158;8770.473771573726;5242.790288388498;5398.2982212312145;5398.811041613525;5400.540954018913;5411.945880541183;3440.3291358185165;3521.964780352215;3583.605803361628;3597.2985267872778;3602.4776407420777;994.1500017485091;1018.2668478695327;1020.6835880876952;1021.5760459409336;1025.7486915705624;11560.075193984461;11663.76672466546;11675.883241167565;11678.416079196006;11717.53129874804;4481.574250446202;4491.865244042684;4588.518205588432;4614.984703365254;4626.745528995937;8961.910380896108;9010.549472526347;9035.864462033074;9143.177114059767;9149.634014639472;5204.583633309402;5204.7918083277;5209.47905209476;5212.7914883405;5222.817201397933;3630.582483014487;4152.91892129689;4347.7173983690955;4494.5730155635165;4713.858584242478;3152.4798408262836;3212.823294718813;3748.793816340073;3806.980965095157;3852.2295540891932;1113.1818113686434;1140.811766058591;1154.7113221694576;1165.3357586175969;1174.3775798826637;823.6046697585073;852.86780549015;884.8186121845006;909.2725819344498;941.4304345870787;4620.422447194101;5428.4028756836715;5638.210650508932;5732.140178973104;5734.483896449339;916.2532535983153;963.797602503468;976.1605019683067;978.496074521622;990.5641517732175;3751.943720844135;3849.4418309344987;3891.2412079644764;3913.941290880639;3928.194720082376;10280.383177009404;10582.470876456146;10621.787564248672;10621.787564248672;10636.946815265874;9919.40483570988;10400.635977740743;10403.271770976111;10477.738056548647;10609.57561697539;7990.161033091558;8111.067227268703;8143.389245806588;8220.424570280024;8238.052623948202;5342.385625652944;5735.426457354398;5741.655500669973;5822.272215973004;5916.49709774674;9687.951560242154;9872.40765554069;9922.553485093043;9961.053699898497;9971.152452041657;9503.097205765442;9914.603415863574;9921;10070.295079344376;10094.394336339843;6884.345987131214;7133.2153463117975;7232.349088582053;7502.849943001219;7504.437167212372;8240.011198656199;8241.134680858664;8243.793905152419;8339.332853371594;8345.624446900025;7270.418366530559;7435.405167586689;7453.366463850417;7560.886586701314;7567.167611562613;7207.9279207207255;7268.41852651776;7280.344768970818;7420.5176663516695;7437.962810186131;6926.480513961373;6938.722451102101;6995.090638217;6998.825029374103;7036.964815175891;4573.839915602938;4740.834070807505;4755.690880092783;4883.316335713074;4929.112759703288;4825.6139508838505;4868.269759536098;4938.185199442052;4987.501249874984;5130.307408499954 16:44:37 INFO - PROCESS | 1930 | |4;prototype.html;298.74009611637865;305.65003071483153;313.3763810018064;318.12991468334104;318.2029017311634;4069.491313585802;5025.7235852028125;5070.391553013639;5085.720285384303;5135.409427915795;4962.727050012248;4963.379577552806;5049.722265275408;5069.79720811168;5097.286379906816;3422.863085476578;3906.45309656649;3915.353966595508;3920.7255492115564;3927.429028388653;3537.734669899755;3692.612275711052;3944.7633142011573;3946.5461471930666;3965.762054276738;3072.4008818280445;3852.4028775539696;3884.9028774280587;3908.9804550977206;3920.1571662092642;3814.618538146191;3831.750936189139;3844.384898416254;3845.9423108653255;3858.536975562922;2758.3104223944015;3167.228674909454;3215.565898603682;3325.517799918998;3339.5825521809775;4864.537868902448;4899;4899.363082799249;4900.97079613278;4915.188993816052;3736.869209577652;3833.731638785286;4140.275451795954;4385.934210986838;4413.977930110361;4471.932921006188;5320.175372817181;5350.314650884998;5355;5378.650387724785;3208.4385232584436;3407.4207384744404;3528.6591095383847;3662.7802331860175;3675.3935600625864;4674.99487610162;4898.926516102261;5152.4847515248175;5554.361248456398;5594.021046316879;3229.951550726741;3385.830708464567;3606.531150950412;3766.6610005099674;3768.378217594121;3240.9675903241136;3483.9303213936087;3757.5866654667966;3803.91588397311;3815.73289869712;3998.4202290668272;3998.9202915213314;4047.9677682190854;4326.307790753464;4493.348464472699;4415.735055896656;4835.975820120877;4944.505549445099;5121.615878809104;5393.946060539422;1097.5938902605926;1134.551852018448;1301.537954026326;1310.4037662863375;1315.3423288355823;747.7794050755041;783.9137694853562;791.2996997657115;799.7840583042662;800.2237829305565;3874.9225015499533;4090.1206240657807;4173.561776013536;4180.853670121592;4195.3916682081535;4100.462326627515;4390.102532328711;4561.221123761736;4591.632669386441;4633.351330813689;4160.791960401968;4162.5629308922735;4164.875053748392;4183.247015537172;4198.538160802309;2208.436848603596;2225.3880182949492;2353.2349489349945;2441.2920253126395;2494.6881639795024;4118.91762164755;4196.426340122454;4481.820727170942;4824.710517368969;4842.93912861061;4031.314676505001;4266.360045993126;4628.866751554204;4641.95358046422;4732.63368315842;4325.7837108145095;4338.110687309094;4499.510597071568;4662.836800711959;4723.692959957661;3992.7041950878943;4076.6331030207425;4335.891602709958;4625.658559017847;4640.215870477687;3988.3020471417967;4028.8388464461095;4301.548337424525;4490.350693091092;4577.382053422745;4273.807678654406;4458.621017213573;4920.76105372062;5143.685341596221;5526.226328313959;3438.3123375325536;3613.1689711366007;3708.9629103708617;3709.9814500927323;3717.5353080864893;5033.602318435115;5109.397874498395;5287.514868012028;5511.917321240105;5525.778968841201;4962.826301079445;5326.760295786621;5328.626996110235;5345.54562862161;5372.758225879923;4495.056038231877;5308.920366194433;5338.412774594869;5339.546138578109;5368.677879327252;4544.296652964166;4819.95180048195;5214.791408343775;5242.58059355243;5260.579153667621;4706.458757242824;5012.548870601663;5068.467810879804;5317.0858490942455;5318.122509785842;4740.928886066781;5202.531772140374;5231.529162375405;5276.551493122974;5296.735163241746 16:44:37 INFO - PROCESS | 1930 | |5;yui.html;619.1919544993783;649.2176926803203;714.0610097721495;731.104397113536;749.1160430691783;13339.93280537557;14858.182799945998;14954.626134346634;15176.468823591176;17092.48722538323;16231.107289099094;16366.181690915466;16404.769642276817;16413.507594772145;16437.753433698475;706.2161001288565;731.989020164698;885.3625389719408;899.0110878034143;899.9325050621229;873.8033942362949;902.4946030223064;903.336048004716;905.1988989744091;907.4011152639259;797.3342259213564;928.9071092890724;940.463935556733;940.7459985803829;941.7316064921499;737.3511310047178;898.9775255618662;930.3394589841221;935.0322416299132;938.4228699349885;691.7751730687546;691.989620155698;692.6398272898115;692.882210024297;693.639307560066;14951.551453456304;14975.700485990219;14980;14990.325435355435;14991.550253492413;12017.93991030048;12046.337451440168;12151.756964860742;12289.93855030728;12301.384930753517;10797.082248008928;10887;10968.23222374434;11024;11413.60052398162;3978.840846366171;4067;4120.670346372283;4122.196171746511;4123.9381409278885;86152.70770938504;88956;88979.55510222407;89340.55329723375;90856;9220.308476864262;9247.352685312098;9382.390144640576;9468.95265523668;9692.9030709692;20664.380068597966;20689.68965465519;20690.275840345515;20703.58592828135;20737.79262207389;7217.350438460564;7239.167495738057;7282.526635768658;7399.852002960019;7694.807629809187;4013.1973605278476;4063.1061166543313;4079.265732168238;4079.6124368184974;4085.632293093636;2689.462107578492;3390.4744764561533;3392.8982130536124;3400.4559270516597;3405.063607507955;611.2939554814196;616.4174854762207;618.8391018335175;628.5631486117105;641.403494749887;514.8429728932637;589.2339958054512;592.4756590417426;620.5997131849931;622.4802290087737;7839.764807055797;7950.6422211000645;8033.5179889205665;8079.070906845671;8195.180481951757;494.658685506999;549.2146230889786;581.1950448628679;585.7569108819878;588.8037619678623;3819.9427008594894;3873.903152421212;3875.844966201377;3892.591277915778;4051.554329023805;86421.56789216014;89847;92487.61268580977;92548;92665;81234;81755;81878;82517.894106792;82940.58529707433;53728.92542149057;72982.0807167718;73620;74096.51806963938;74418;7243.8409854425145;7488.475806693697;7560.8487830244185;7652.196519365387;7663.386929045551;35906.38421270997;39391;44964.55035449604;46670.76664616655;47104.7644761774;63951.360486394544;65067.67466162639;65187.67406162939;65360.67319663371;65540.67229663821;10242.743931401774;10251.7437064074;10892.074173695008;11159.776804464027;11177.55289788432;11765.294082355085;12010.939945300217;12234.510619575118;12723.80914286268;12936.41786119608;7672.6547305372505;11181.273217240854;11242;11397.031252343311;11714.23857449263;9501.477418742035;11203.719907002063;11367.886321137014;11400.030997365317;11523.884761152281;10471.738206544896;10584.941505849354;11037.72405689832;11094.944525277322;11451.942740286246;9183.83111478789;9282.72151835446;9420.057994200524;9443.763905902408;9703.17523010552;8748.868766968628;9551.522423878918;9755.707328780149;9800.460974646463;9887.30788844774 16:44:37 INFO - PROCESS | 1930 | __end_tp_report 16:44:37 INFO - PROCESS | 1930 | __start_cc_report 16:44:37 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1190 16:44:37 INFO - PROCESS | 1930 | __end_cc_report 16:44:37 INFO - PROCESS | 1930 | __startTimestamp1464306277614__endTimestamp 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | ------- Summary: start ------- 16:44:37 INFO - PROCESS | 1930 | Number of tests: 6 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5672.04 Median:5821.45 stddev:640.13 (11.0%) stddev-sans-first:616.64 16:44:37 INFO - PROCESS | 1930 | Values: 3393.9 3405.3 3423.8 3444.5 3477.9 5526.9 5569.7 5578.1 5698.1 5721.7 5584.4 5595.9 5670.9 5715.9 5749.0 5000.6 5039.3 5109.7 5130.6 5214.5 5173.7 5185.9 5186.5 5236.7 5264.7 5038.4 5090.8 5092.7 5141.5 5171.4 5161.4 5164.9 5167.9 5169.3 5169.7 5071.8 5113.6 5120.4 5136.4 5142.7 5529.6 5553.1 5561.1 5561.2 5567.2 6043.2 6060.8 6077.2 6134.9 6135.4 6131.2 6238.8 6253.2 6254.5 6260.8 5754.7 5792.9 5810.0 5820.3 5823.8 5275.9 6206.7 6242.6 6281.2 6312.2 5079.9 6081.6 6087.6 6120.4 6122.8 6067.3 6259.4 6263.2 6264.1 6283.6 6053.9 6072.0 6115.5 6134.2 6143.2 6088.6 6157.2 6195.0 6200.2 6210.3 4191.4 4858.6 4860.3 4873.3 4910.7 4303.3 4304.3 4371.0 4371.7 4424.6 5642.4 5681.1 5688.9 5691.5 5693.6 6119.5 6291.4 6316.4 6319.4 6332.0 5581.7 5602.9 5630.5 5677.2 5685.3 5289.4 5306.9 5318.0 5322.8 5325.5 6255.3 6310.0 6317.3 6334.0 6448.7 6147.1 6212.4 6216.2 6288.7 6290.9 6295.1 6323.8 6325.7 6329.0 6331.0 5934.1 6030.5 6174.1 6174.4 6197.8 6109.5 6206.8 6247.1 6256.5 6262.5 5897.8 5987.5 6004.0 6036.7 6065.3 5796.2 5835.1 5877.9 5969.3 6078.4 5821.4 5827.6 5834.7 5857.3 5870.5 5943.1 5950.4 5957.9 5992.1 5995.6 5631.3 5639.7 5695.4 5772.3 5811.2 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:59270.90 Median:35773.05 stddev:125094.13 (349.7%) stddev-sans-first:125367.16 16:44:37 INFO - PROCESS | 1930 | Values: 475.1 494.1 494.1 500.7 512.9 31090.4 32877.8 35680.0 42103.7 43606.6 45099.5 45339.8 45436.1 45495.8 45506.5 9358.5 9579.1 9581.1 9584.5 9586.7 8503.3 8736.1 9453.1 11616.2 11628.1 11658.7 11749.2 11751.4 11768.8 11774.1 8463.1 8667.1 11691.6 11794.0 11809.6 11382.7 11799.9 11812.8 11827.6 11829.6 41500.4 41923.6 41928.0 41951.6 42054.9 3111.2 3163.8 3281.1 3316.4 3458.0 28994.6 29097.9 29587.7 29599.9 29619.9 2680.0 2699.1 2707.3 2754.4 2801.6 482245.0 491693.0 493347.0 494868.0 497509.0 63492.7 64758.0 64862.7 64916.0 65209.0 35292.8 35762.6 35783.5 36937.6 36978.8 16655.3 16790.2 16852.3 16869.6 16905.1 11623.8 11900.9 12586.7 12796.0 12909.0 19915.5 20555.8 20598.4 20611.4 20652.5 8141.8 8145.0 8154.0 8187.9 8218.3 901.4 939.6 957.0 976.8 1010.8 29768.0 29878.7 30017.7 30799.0 30904.4 1107.6 1109.5 1125.2 1158.0 1175.8 15363.6 15417.7 15424.0 15806.1 15848.7 187445.1 187597.0 188420.1 188712.0 188968.0 61695.7 62225.4 62238.4 62324.8 62366.7 1302.6 1430.7 1454.8 1469.9 1473.9 30819.7 30835.0 30949.0 31592.7 31715.8 53065.5 56568.7 60687.1 60756.0 64405.7 605536.0 611816.0 614132.0 617574.0 617701.0 35805.1 41664.6 42014.6 42918.0 43778.0 47323.1 47395.5 47754.8 49078.5 49262.3 37720.4 38875.8 39188.4 39686.0 40152.8 37760.0 38446.0 38568.0 39602.8 39621.0 37994.1 38265.0 38656.0 39231.4 41224.0 32103.7 34293.7 34860.7 35209.1 35402.5 25155.9 25200.4 25354.4 25477.6 25672.9 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:33648.44 Median:2397.87 stddev:98727.29 (4117.3%) stddev-sans-first:98972.64 16:44:37 INFO - PROCESS | 1930 | Values: 377.1 377.2 385.5 398.7 401.7 19195.5 20666.3 21279.1 21574.1 21574.4 20248.8 20668.3 21143.6 21348.9 21367.0 5261.8 5668.8 5908.3 6087.5 6092.5 5954.2 5966.3 5992.6 6051.3 6059.3 5748.0 5876.5 5909.6 5912.3 5930.8 5793.9 5834.4 5867.1 5875.4 5879.5 5035.4 5175.3 5195.4 5205.6 5210.5 25571.0 25697.5 25825.4 26139.9 26155.5 1839.3 2110.5 2155.4 2363.0 2436.8 8127.7 9670.3 9911.2 9925.5 9983.6 1720.6 1788.2 1834.2 1838.2 1845.2 87070.6 99626.5 104071.0 129263.0 129421.1 11433.1 11559.6 11681.9 11683.1 11813.6 14695.6 14834.6 14838.4 14842.3 14845.3 8477.4 8649.5 8678.4 8755.0 8768.3 8526.7 8540.4 8543.4 8662.9 8686.8 3748.0 4394.0 4504.4 4590.6 4657.1 1621.0 1699.4 1937.3 1939.8 1942.5 817.0 872.5 911.3 919.0 999.6 20040.0 20228.3 20262.1 20397.4 20506.1 912.9 927.4 949.2 1014.5 1037.5 10154.8 10507.4 10744.5 10749.4 10784.7 587546.1 596194.0 596630.0 597198.0 597598.0 77022.2 77064.0 85364.0 134791.0 139324.0 990.8 992.5 1006.5 1039.6 1041.6 17168.1 17516.5 17564.9 17674.8 17795.4 53814.7 53990.0 54468.0 55863.4 56014.0 49610.0 70487.0 70523.6 77848.0 83613.6 7546.0 7751.7 7811.8 7865.0 8090.2 8560.0 9287.0 9893.5 10976.9 11147.6 10274.9 10367.9 10460.8 10589.5 10724.1 8276.3 10225.0 10356.2 10494.2 10534.1 10467.9 10477.9 10536.7 10662.1 10806.4 7703.0 7797.7 7881.8 7922.8 8188.2 9133.8 10056.4 10331.5 10667.2 10922.7 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5590.11 Median:5167.45 stddev:3152.32 (61.0%) stddev-sans-first:3145.32 16:44:37 INFO - PROCESS | 1930 | Values: 1384.3 1395.4 1500.7 1542.3 1954.8 7753.0 8461.9 8748.2 8768.8 8907.0 8477.3 8723.2 8766.0 8780.8 8782.1 1968.4 2376.9 2380.1 2381.4 2381.4 2321.8 2354.2 2380.3 2383.5 2383.6 2314.7 2318.0 2325.0 2347.3 2351.1 2309.1 2336.3 2341.7 2346.8 2356.9 2341.8 2343.3 2350.2 2351.5 2355.9 8463.7 8652.3 8715.0 8717.8 8770.5 5242.8 5398.3 5398.8 5400.5 5411.9 3440.3 3522.0 3583.6 3597.3 3602.5 994.2 1018.3 1020.7 1021.6 1025.7 11560.1 11663.8 11675.9 11678.4 11717.5 4481.6 4491.9 4588.5 4615.0 4626.7 8961.9 9010.5 9035.9 9143.2 9149.6 5204.6 5204.8 5209.5 5212.8 5222.8 3630.6 4152.9 4347.7 4494.6 4713.9 3152.5 3212.8 3748.8 3807.0 3852.2 1113.2 1140.8 1154.7 1165.3 1174.4 823.6 852.9 884.8 909.3 941.4 4620.4 5428.4 5638.2 5732.1 5734.5 916.3 963.8 976.2 978.5 990.6 3751.9 3849.4 3891.2 3913.9 3928.2 10280.4 10582.5 10621.8 10621.8 10636.9 9919.4 10400.6 10403.3 10477.7 10609.6 7990.2 8111.1 8143.4 8220.4 8238.1 5342.4 5735.4 5741.7 5822.3 5916.5 9688.0 9872.4 9922.6 9961.1 9971.2 9503.1 9914.6 9921.0 10070.3 10094.4 6884.3 7133.2 7232.3 7502.8 7504.4 8240.0 8241.1 8243.8 8339.3 8345.6 7270.4 7435.4 7453.4 7560.9 7567.2 7207.9 7268.4 7280.3 7420.5 7438.0 6926.5 6938.7 6995.1 6998.8 7037.0 4573.8 4740.8 4755.7 4883.3 4929.1 4825.6 4868.3 4938.2 4987.5 5130.3 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4065.69 Median:4337.00 stddev:1229.91 (28.4%) stddev-sans-first:1200.42 16:44:37 INFO - PROCESS | 1930 | Values: 298.7 305.7 313.4 318.1 318.2 4069.5 5025.7 5070.4 5085.7 5135.4 4962.7 4963.4 5049.7 5069.8 5097.3 3422.9 3906.5 3915.4 3920.7 3927.4 3537.7 3692.6 3944.8 3946.5 3965.8 3072.4 3852.4 3884.9 3909.0 3920.2 3814.6 3831.8 3844.4 3845.9 3858.5 2758.3 3167.2 3215.6 3325.5 3339.6 4864.5 4899.0 4899.4 4901.0 4915.2 3736.9 3833.7 4140.3 4385.9 4414.0 4471.9 5320.2 5350.3 5355.0 5378.7 3208.4 3407.4 3528.7 3662.8 3675.4 4675.0 4898.9 5152.5 5554.4 5594.0 3230.0 3385.8 3606.5 3766.7 3768.4 3241.0 3483.9 3757.6 3803.9 3815.7 3998.4 3998.9 4048.0 4326.3 4493.3 4415.7 4836.0 4944.5 5121.6 5393.9 1097.6 1134.6 1301.5 1310.4 1315.3 747.8 783.9 791.3 799.8 800.2 3874.9 4090.1 4173.6 4180.9 4195.4 4100.5 4390.1 4561.2 4591.6 4633.4 4160.8 4162.6 4164.9 4183.2 4198.5 2208.4 2225.4 2353.2 2441.3 2494.7 4118.9 4196.4 4481.8 4824.7 4842.9 4031.3 4266.4 4628.9 4642.0 4732.6 4325.8 4338.1 4499.5 4662.8 4723.7 3992.7 4076.6 4335.9 4625.7 4640.2 3988.3 4028.8 4301.5 4490.4 4577.4 4273.8 4458.6 4920.8 5143.7 5526.2 3438.3 3613.2 3709.0 3710.0 3717.5 5033.6 5109.4 5287.5 5511.9 5525.8 4962.8 5326.8 5328.6 5345.5 5372.8 4495.1 5308.9 5338.4 5339.5 5368.7 4544.3 4820.0 5214.8 5242.6 5260.6 4706.5 5012.5 5068.5 5317.1 5318.1 4740.9 5202.5 5231.5 5276.6 5296.7 16:44:37 INFO - PROCESS | 1930 | 16:44:37 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:18292.22 Median:619.90 stddev:26106.91 (4211.5%) stddev-sans-first:26146.42 16:44:37 INFO - PROCESS | 1930 | Values: 619.2 649.2 714.1 731.1 749.1 13339.9 14858.2 14954.6 15176.5 17092.5 16231.1 16366.2 16404.8 16413.5 16437.8 706.2 732.0 885.4 899.0 899.9 873.8 902.5 903.3 905.2 907.4 797.3 928.9 940.5 940.7 941.7 737.4 899.0 930.3 935.0 938.4 691.8 692.0 692.6 692.9 693.6 14951.6 14975.7 14980.0 14990.3 14991.6 12017.9 12046.3 12151.8 12289.9 12301.4 10797.1 10887.0 10968.2 11024.0 11413.6 3978.8 4067.0 4120.7 4122.2 4123.9 86152.7 88956.0 88979.6 89340.6 90856.0 9220.3 9247.4 9382.4 9469.0 9692.9 20664.4 20689.7 20690.3 20703.6 20737.8 7217.4 7239.2 7282.5 7399.9 7694.8 4013.2 4063.1 4079.3 4079.6 4085.6 2689.5 3390.5 3392.9 3400.5 3405.1 611.3 616.4 618.8 628.6 641.4 514.8 589.2 592.5 620.6 622.5 7839.8 7950.6 8033.5 8079.1 8195.2 494.7 549.2 581.2 585.8 588.8 3819.9 3873.9 3875.8 3892.6 4051.6 86421.6 89847.0 92487.6 92548.0 92665.0 81234.0 81755.0 81878.0 82517.9 82940.6 53728.9 72982.1 73620.0 74096.5 74418.0 7243.8 7488.5 7560.8 7652.2 7663.4 35906.4 39391.0 44964.6 46670.8 47104.8 63951.4 65067.7 65187.7 65360.7 65540.7 10242.7 10251.7 10892.1 11159.8 11177.6 11765.3 12010.9 12234.5 12723.8 12936.4 7672.7 11181.3 11242.0 11397.0 11714.2 9501.5 11203.7 11367.9 11400.0 11523.9 10471.7 10584.9 11037.7 11094.9 11451.9 9183.8 9282.7 9420.1 9443.8 9703.2 8748.9 9551.5 9755.7 9800.5 9887.3 16:44:37 INFO - PROCESS | 1930 | -------- Summary: end -------- 16:44:37 INFO - PROCESS | 1930 | 16:44:38 INFO - TEST-INFO | 1930: exit 0 16:44:38 INFO - TEST-OK | dromaeo_css | took 1115508ms 16:44:38 INFO - TEST-START | kraken 16:44:38 INFO - operating with platform_type : mac_ 16:44:38 INFO - Initialising browser for kraken test... 16:44:38 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpG6DCQU/profile http://localhost:49710/getInfo.html) 16:44:40 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 16:44:40 INFO - PROCESS | 1939 | colorDepth:24 16:44:40 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 16:44:40 INFO - PROCESS | 1939 | __metrics 16:44:40 INFO - PROCESS | 1939 | 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:44:40 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:44:41 INFO - TEST-INFO | 1939: exit 0 16:44:41 INFO - Browser initialized. 16:44:41 INFO - Running cycle 1/1 for kraken test... 16:44:41 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpG6DCQU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:53 INFO - PROCESS | 1940 | RSS: Main: 241246208 16:44:53 INFO - PROCESS | 1940 | 16:44:55 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-beat-detection) 16:44:56 INFO - PROCESS | 1940 | RSS: Main: 289185792 16:44:56 INFO - PROCESS | 1940 | 16:44:59 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-dft) 16:45:00 INFO - PROCESS | 1940 | RSS: Main: 299560960 16:45:00 INFO - PROCESS | 1940 | 16:45:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-fft) 16:45:04 INFO - PROCESS | 1940 | RSS: Main: 386224128 16:45:04 INFO - PROCESS | 1940 | 16:45:06 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-oscillator) 16:45:06 INFO - PROCESS | 1940 | RSS: Main: 288292864 16:45:06 INFO - PROCESS | 1940 | 16:45:09 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:45:09 INFO - PROCESS | 1940 | RSS: Main: 292081664 16:45:09 INFO - PROCESS | 1940 | 16:45:12 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-darkroom) 16:45:13 INFO - PROCESS | 1940 | RSS: Main: 330076160 16:45:13 INFO - PROCESS | 1940 | 16:45:16 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-desaturate) 16:45:16 INFO - PROCESS | 1940 | RSS: Main: 331124736 16:45:16 INFO - PROCESS | 1940 | 16:45:19 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49710/tests/kraken/driver.html?testName=json-parse-financial) 16:45:20 INFO - PROCESS | 1940 | RSS: Main: 331001856 16:45:20 INFO - PROCESS | 1940 | 16:45:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49710/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:45:22 INFO - PROCESS | 1940 | RSS: Main: 317374464 16:45:22 INFO - PROCESS | 1940 | 16:45:24 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:45:24 INFO - PROCESS | 1940 | RSS: Main: 323919872 16:45:24 INFO - PROCESS | 1940 | 16:45:27 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:45:27 INFO - PROCESS | 1940 | RSS: Main: 325763072 16:45:27 INFO - PROCESS | 1940 | 16:45:30 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:45:31 INFO - PROCESS | 1940 | RSS: Main: 330760192 16:45:31 INFO - PROCESS | 1940 | 16:45:34 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:45:34 INFO - PROCESS | 1940 | RSS: Main: 328630272 16:45:34 INFO - PROCESS | 1940 | 16:45:36 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49710/tests/kraken/driver.html?testName=ai-astar) 16:45:37 INFO - PROCESS | 1940 | RSS: Main: 374984704 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | __start_tp_report 16:45:37 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 16:45:37 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 16:45:37 INFO - PROCESS | 1940 | |i|pagename|runs| 16:45:37 INFO - PROCESS | 1940 | |0;ai-astar;112;107;113;114;113;113;113;112;113;113 16:45:37 INFO - PROCESS | 1940 | |1;audio-beat-detection;172;147;116;170;121;131;143;117;182;143 16:45:37 INFO - PROCESS | 1940 | |2;audio-dft;185;185;183;185;184;183;183;182;183;183 16:45:37 INFO - PROCESS | 1940 | |3;audio-fft;78;58;77;111;81;74;64;57;96;56 16:45:37 INFO - PROCESS | 1940 | |4;audio-oscillator;90;92;134;101;73;69;111;55;66;114 16:45:37 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;90;86;90;88;89;87;90;83;91;85 16:45:37 INFO - PROCESS | 1940 | |6;imaging-darkroom;94;95;95;95;101;95;95;96;94;99 16:45:37 INFO - PROCESS | 1940 | |7;imaging-desaturate;85;84;84;86;86;83;87;84;85;84 16:45:37 INFO - PROCESS | 1940 | |8;json-parse-financial;57;55;59;56;58;56;55;77;57;55 16:45:37 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;72;65;70;73;75;71;70;71;71;70 16:45:37 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;98;103;108;87;84;85;83;87;86;88 16:45:37 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;149;140;147;146;144;149;139;137;178;149 16:45:37 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;171;224;196;169;165;165;162;163;162;164 16:45:37 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;67;66;65;68;79;84;85;86;68;66 16:45:37 INFO - PROCESS | 1940 | __end_tp_report 16:45:37 INFO - PROCESS | 1940 | __start_cc_report 16:45:37 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2058 16:45:37 INFO - PROCESS | 1940 | __end_cc_report 16:45:37 INFO - PROCESS | 1940 | __startTimestamp1464306337055__endTimestamp 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | ------- Summary: start ------- 16:45:37 INFO - PROCESS | 1940 | Number of tests: 14 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:1.95 (1.7%) stddev-sans-first:2.06 16:45:37 INFO - PROCESS | 1940 | Values: 112.0 107.0 113.0 114.0 113.0 113.0 113.0 112.0 113.0 113.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:145.00 stddev:23.86 (16.5%) stddev-sans-first:23.09 16:45:37 INFO - PROCESS | 1940 | Values: 172.0 147.0 116.0 170.0 121.0 131.0 143.0 117.0 182.0 143.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:183.60 Median:183.50 stddev:1.07 (0.6%) stddev-sans-first:1.01 16:45:37 INFO - PROCESS | 1940 | Values: 185.0 185.0 183.0 185.0 184.0 183.0 183.0 182.0 183.0 183.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:75.20 Median:75.50 stddev:17.89 (23.7%) stddev-sans-first:18.95 16:45:37 INFO - PROCESS | 1940 | Values: 78.0 58.0 77.0 111.0 81.0 74.0 64.0 57.0 96.0 56.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:67.50 stddev:24.96 (37.0%) stddev-sans-first:26.47 16:45:37 INFO - PROCESS | 1940 | Values: 90.0 92.0 134.0 101.0 73.0 69.0 111.0 55.0 66.0 114.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.50 stddev:2.60 (2.9%) stddev-sans-first:2.65 16:45:37 INFO - PROCESS | 1940 | Values: 90.0 86.0 90.0 88.0 89.0 87.0 90.0 83.0 91.0 85.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:95.00 stddev:2.28 (2.4%) stddev-sans-first:2.32 16:45:37 INFO - PROCESS | 1940 | Values: 94.0 95.0 95.0 95.0 101.0 95.0 95.0 96.0 94.0 99.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 16:45:37 INFO - PROCESS | 1940 | Values: 85.0 84.0 84.0 86.0 86.0 83.0 87.0 84.0 85.0 84.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:57.00 stddev:6.64 (11.6%) stddev-sans-first:7.02 16:45:37 INFO - PROCESS | 1940 | Values: 57.0 55.0 59.0 56.0 58.0 56.0 55.0 77.0 57.0 55.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:2.57 (3.6%) stddev-sans-first:2.69 16:45:37 INFO - PROCESS | 1940 | Values: 72.0 65.0 70.0 73.0 75.0 71.0 70.0 71.0 71.0 70.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:86.50 stddev:8.80 (10.2%) stddev-sans-first:8.95 16:45:37 INFO - PROCESS | 1940 | Values: 98.0 103.0 108.0 87.0 84.0 85.0 83.0 87.0 86.0 88.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:148.00 stddev:11.50 (7.8%) stddev-sans-first:12.19 16:45:37 INFO - PROCESS | 1940 | Values: 149.0 140.0 147.0 146.0 144.0 149.0 139.0 137.0 178.0 149.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:167.00 stddev:20.25 (12.1%) stddev-sans-first:21.44 16:45:37 INFO - PROCESS | 1940 | Values: 171.0 224.0 196.0 169.0 165.0 165.0 162.0 163.0 162.0 164.0 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:73.50 stddev:8.92 (12.1%) stddev-sans-first:9.16 16:45:37 INFO - PROCESS | 1940 | Values: 67.0 66.0 65.0 68.0 79.0 84.0 85.0 86.0 68.0 66.0 16:45:37 INFO - PROCESS | 1940 | -------- Summary: end -------- 16:45:37 INFO - PROCESS | 1940 | 16:45:37 INFO - TEST-INFO | 1940: exit 0 16:45:37 INFO - TEST-OK | kraken | took 59629ms 16:45:37 INFO - SUITE-END | took 1175s 16:45:38 INFO - Completed test suite (00:19:35) 16:45:38 INFO - javascript benchmark 16:45:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3393.8981830545085, 3405.267867408505, 3423.760336776426, 3444.517767512547, 3477.9304413911705, 5526.889462210754, 5569.749361278742, 5578.135389014704, 5698.059820129679, 5721.6853073080965, 5584.4136365681525, 5595.860103497404, 5670.886582268352, 5715.885682286352, 5749.022666146755, 5000.574951129159, 5039.31969184161, 5109.718965456898, 5130.6152038597065, 5214.4524824893215, 5173.663711858736, 5185.948140518603, 5186.507281808241, 5236.659617124894, 5264.657797243167, 5038.445770965191, 5090.77091530882, 5092.694438333712, 5141.511556402136, 5171.405288391846, 5161.4322424533275, 5164.948350516484, 5167.870803229912, 5169.276301317818, 5169.689818610896, 5071.822486213002, 5113.616478764107, 5120.411152717448, 5136.43499215086, 5142.717150556718, 5529.585281103933, 5553.139263414178, 5561.082421400464, 5561.249231353756, 5567.192757050246, 6043.154037871512, 6060.81685382091, 6077.221614090946, 6134.9079763803575, 6135.417135372132, 6131.233595800525, 6238.781642642532, 6253.249610046823, 6254.530910181755, 6260.780872669435, 5754.712264386764, 5792.9283082629845, 5809.9832529307205, 5820.272465941757, 5823.79616713413, 5275.947240527622, 6206.689665516707, 6242.563020588606, 6281.214848143982, 6312.210973628296, 5079.8984020319385, 6081.610060885959, 6087.633962904846, 6120.392997855451, 6122.785702500391, 6067.271927368744, 6259.374062593795, 6263.154474145932, 6264.060390941304, 6283.59156654816, 6053.939460605426, 6071.967765479879, 6115.51416190291, 6134.228656665725, 6143.170671959318, 6088.6042407243385, 6157.199564056644, 6194.969025154845, 6200.193974783249, 6210.285817130997, 4191.371294305817, 4858.6356023298395, 4860.295257187688, 4873.26900964851, 4910.656254062253, 4303.289957157097, 4304.311310190355, 4371.0, 4371.672124590668, 4424.646028317727, 5642.379338272869, 5681.062624666966, 5688.857778555486, 5691.544676425876, 5693.629914055573, 6119.510439164945, 6291.370862913672, 6316.431521163117, 6319.431251187415, 6331.96834015827, 5581.665100094007, 5602.887942241132, 5630.521405680481, 5677.205191273225, 5685.317761868602, 5289.365276166807, 5306.92039619406, 5318.042752304622, 5322.813701520428, 5325.4940780625775, 6255.343188965093, 6309.968450157719, 6317.305096439388, 6334.049892515975, 6448.709808058648, 6147.108669243024, 6212.409821066992, 6216.222972128484, 6288.717007734755, 6290.874182516232, 6295.087212354275, 6323.841903952438, 6325.746970121144, 6328.968355158195, 6331.0, 5934.139549765347, 6030.48740857032, 6174.073888916699, 6174.413430724074, 6197.783077592263, 6109.526129009804, 6206.844828879314, 6247.09417134522, 6256.468200203034, 6262.530310226843, 5897.823065308047, 5987.491063259672, 6004.039353703386, 6036.667983260787, 6065.332813390612, 5796.246487956539, 5835.124731290341, 5877.911831322448, 5969.283685957713, 6078.361772014052, 5821.446962538552, 5827.562932779973, 5834.679092649775, 5857.26784151981, 5870.471657550841, 5943.138244954544, 5950.404959504015, 5957.91063134062, 5992.0712289595185, 5995.61028533144, 5631.267935168402, 5639.661620302795, 5695.373508913934, 5772.278465191851, 5811.157382179645], "value": 5631.632791910439, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.0617530377507, 494.0810093226598, 494.09580467744007, 500.7496251874063, 512.8767998084196, 31090.37819243614, 32877.835610821945, 35680.0, 42103.736887893414, 43606.563934360725, 45099.54900450994, 45339.77330113345, 45436.0912781745, 45495.77252113735, 45506.54493455064, 9358.532073396336, 9579.137877591016, 9581.137697607213, 9584.520773961274, 9586.712398628053, 8503.319734421262, 8736.126387361244, 9453.149216570508, 11616.186866919319, 11628.069754419626, 11658.708532286675, 11749.236299640539, 11751.353675547842, 11768.7646247075, 11774.116941229398, 8463.111373305775, 8667.133286671282, 11691.590794322201, 11793.997510211642, 11809.645710628694, 11382.658520244406, 11799.94100029503, 11812.822807657893, 11827.586034488751, 11829.645110646694, 41500.37749433761, 41923.58076419227, 41928.0, 41951.580484195074, 42054.948626284284, 3111.23774675206, 3163.794353367047, 3281.1469018055477, 3316.403047451482, 3458.0317511096932, 28994.565081523793, 29097.854510727524, 29587.70412295871, 29599.852000740073, 29619.851900740574, 2679.973200267992, 2699.1362763915554, 2707.323169207698, 2754.380264440505, 2801.6217810595513, 482244.9999999965, 491693.0, 493347.0, 494868.0, 497509.0, 63492.68253658795, 64758.028629570596, 64862.675686622206, 64916.0, 65209.02186467207, 35292.82353588267, 35762.64237357593, 35783.4632480513, 36937.63062369342, 36978.815105924295, 16655.3337866484, 16790.16049197535, 16852.325906963582, 16869.578260543585, 16905.07022113771, 11623.76752464946, 11900.940495297642, 12586.685332866751, 12795.976321894226, 12909.031822613342, 19915.502112447306, 20555.79444205569, 20598.38204853857, 20611.38165855027, 20652.483687907923, 8141.755747327589, 8144.959275203586, 8153.959230203811, 8187.877181842277, 8218.301444377175, 901.3600343755876, 939.572494514994, 956.9808154315771, 976.799756050008, 1010.7725761703705, 29768.0, 29878.701212988028, 30017.699823001927, 30799.0, 30904.381912362078, 1107.5514416661265, 1109.5118148014847, 1125.2348403085816, 1157.9826302605468, 1175.7766024455327, 15363.61590960235, 15417.691646167239, 15424.0, 15806.130662813655, 15848.68302633941, 187445.06277468524, 187597.0, 188420.05789970962, 188712.0, 188968.0, 61695.6915215421, 62225.37774622286, 62238.37761622326, 62324.75350492965, 62366.68816655978, 1302.5571305755898, 1430.6995530938411, 1454.810874586297, 1469.941202351894, 1473.852614738539, 30819.69180308258, 30835.0, 30949.0, 31592.684073158973, 31715.84142079275, 53065.469345307596, 56568.7171564156, 60687.089693655515, 60755.99999999823, 64405.67797160984, 605536.0, 611816.0, 614132.0, 617574.0, 617701.0, 35805.10487237736, 41664.58335416607, 42014.57985420229, 42918.0, 43778.0, 47323.053538929715, 47395.52604473911, 47754.761226193645, 49078.50921490882, 49262.26106608476, 37720.43419348657, 38875.805620972846, 39188.41217381685, 39686.0, 40152.79923600363, 37760.0, 38446.0, 38568.035799105244, 39602.801985989885, 39621.0, 37994.0501487465, 38265.0, 38656.0, 39231.41152882766, 41224.0, 32103.678963211005, 34293.657063430044, 34860.65139348574, 35209.119772005906, 35402.46896296609, 25155.87422062878, 25200.369990749645, 25354.366140846625, 25477.643392890554, 25672.871635641703], "value": 19825.14379121071, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.06675976957024, 377.1702255038914, 385.4853770216761, 398.7089424719953, 401.71879684221034, 19195.520111997208, 20666.27668031619, 21279.148834046657, 21574.1370345186, 21574.352769416924, 20248.797512024874, 20668.27661031864, 21143.577128457422, 21348.932553372346, 21367.03848326825, 5261.842144735664, 5668.773249070043, 5908.291005079375, 6087.543434242428, 6092.5125989920925, 5954.166416701665, 5966.284045914496, 5992.5505587080925, 6051.273847138327, 6059.303180134297, 5748.0, 5876.5298776098025, 5909.586328956974, 5912.260967379078, 5930.792422265222, 5793.855153621151, 5834.4165583441745, 5867.1492633568105, 5875.412458754133, 5879.52963762898, 5035.446100928895, 5175.301334319856, 5195.350581177353, 5205.583553315726, 5210.505002024839, 25571.0, 25697.48605027889, 25825.354366140997, 26139.869300653565, 26155.476890462083, 1839.3286450445491, 2110.525131845338, 2155.3749412670463, 2362.9527409451716, 2436.8294219404647, 8127.715529956423, 9670.274729395253, 9911.207103431709, 9925.454100024495, 9983.600655973825, 1720.6214632780768, 1788.2131861980818, 1834.1654547181133, 1838.2371315904018, 1845.1604519943394, 87070.56464717699, 99626.5018674902, 104070.95929040688, 129263.0, 129421.05868411981, 11433.14251431146, 11559.595414160633, 11681.88318116825, 11683.123765717604, 11813.586524471773, 14695.632609184855, 14834.554963351116, 14838.406463741545, 14842.257887105601, 14845.257737113101, 8477.448965817202, 8649.524276164871, 8678.39251252419, 8755.0, 8768.298536117096, 8526.65893364271, 8540.359473039547, 8543.35924805642, 8662.87005694915, 8686.826263474695, 3747.9625203747614, 4394.011347446799, 4504.414426124583, 4590.586847183769, 4657.068586282757, 1620.9675806483804, 1699.354245386745, 1937.2541571495076, 1939.786623471417, 1942.5046613113566, 816.9706170225439, 872.4808738800403, 911.2664305234217, 919.0258326174277, 999.5851721535481, 20040.0, 20228.29200977988, 20262.088206030763, 20397.38807835767, 20506.077226524543, 912.8862787399362, 927.3740225347864, 949.2026697573933, 1014.523174108168, 1037.5382954585136, 10154.847677284846, 10507.369557826554, 10744.516496757664, 10749.355038697704, 10784.730381740519, 587546.0622696945, 596194.0, 596630.0, 597198.0, 597598.0120099372, 77022.2297777015, 77064.0, 85363.99999999876, 134791.0, 139324.0, 990.8117457683018, 992.533509250651, 1006.5017816181037, 1039.6153423233452, 1041.6458404142477, 17168.141592920554, 17516.474505764847, 17564.858284211485, 17674.823251767317, 17795.37716179979, 53814.73092634512, 53990.0, 54468.0, 55863.41365863381, 56014.0, 49610.0, 70487.0, 70523.64738176276, 77848.0, 83613.58193208995, 7545.962270188614, 7751.689932402641, 7811.843763124592, 7864.9606751965875, 8090.1909809018625, 8559.957200214209, 9287.0, 9893.50532473359, 10976.945115274373, 11147.55409783632, 10274.948625256826, 10367.896321036995, 10460.790784184426, 10589.470526473491, 10724.088452481314, 8276.337892968668, 10225.0, 10356.223283253938, 10494.160467162761, 10534.104601108991, 10467.94766026165, 10477.947610262205, 10536.736581585521, 10662.147028237878, 10806.405647689138, 7702.961485192538, 7797.688092476237, 7881.802954926173, 7922.841543169219, 8188.222118898695, 9133.817323653622, 10056.396616203052, 10331.535080921227, 10667.199960003187, 10922.726931826768], "value": 9263.191649587372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.2663388404146, 1395.4487977248994, 1500.7448733715266, 1542.3213785934186, 1954.8045195480445, 7753.030871141107, 8461.915380846189, 8748.212660860521, 8768.824623507526, 8906.955465222682, 8477.279431248351, 8723.171298726627, 8765.991910930245, 8780.824383512341, 8782.121787821216, 1968.370121561101, 2376.8573885566834, 2380.1193558383334, 2381.392744850061, 2381.416552944531, 2321.849079809807, 2354.1760383865594, 2380.297812145421, 2383.5113801670705, 2383.6067048936907, 2314.6875171851752, 2318.0, 2324.9651255231183, 2347.3310106619615, 2351.0948284910346, 2309.1340747219792, 2336.334144768741, 2341.672165896776, 2346.835721499496, 2356.9410764730846, 2341.789238968501, 2343.332150337146, 2350.189184731265, 2351.541449417364, 2355.9411014724596, 8463.703770368069, 8652.264557512619, 8715.0, 8717.825643487158, 8770.473771573726, 5242.790288388498, 5398.2982212312145, 5398.811041613525, 5400.540954018913, 5411.945880541183, 3440.3291358185165, 3521.964780352215, 3583.605803361628, 3597.2985267872778, 3602.4776407420777, 994.1500017485091, 1018.2668478695327, 1020.6835880876952, 1021.5760459409336, 1025.7486915705624, 11560.075193984461, 11663.76672466546, 11675.883241167565, 11678.416079196006, 11717.53129874804, 4481.574250446202, 4491.865244042684, 4588.518205588432, 4614.984703365254, 4626.745528995937, 8961.910380896108, 9010.549472526347, 9035.864462033074, 9143.177114059767, 9149.634014639472, 5204.583633309402, 5204.7918083277, 5209.47905209476, 5212.7914883405, 5222.817201397933, 3630.582483014487, 4152.91892129689, 4347.7173983690955, 4494.5730155635165, 4713.858584242478, 3152.4798408262836, 3212.823294718813, 3748.793816340073, 3806.980965095157, 3852.2295540891932, 1113.1818113686434, 1140.811766058591, 1154.7113221694576, 1165.3357586175969, 1174.3775798826637, 823.6046697585073, 852.86780549015, 884.8186121845006, 909.2725819344498, 941.4304345870787, 4620.422447194101, 5428.4028756836715, 5638.210650508932, 5732.140178973104, 5734.483896449339, 916.2532535983153, 963.797602503468, 976.1605019683067, 978.496074521622, 990.5641517732175, 3751.943720844135, 3849.4418309344987, 3891.2412079644764, 3913.941290880639, 3928.194720082376, 10280.383177009404, 10582.470876456146, 10621.787564248672, 10621.787564248672, 10636.946815265874, 9919.40483570988, 10400.635977740743, 10403.271770976111, 10477.738056548647, 10609.57561697539, 7990.161033091558, 8111.067227268703, 8143.389245806588, 8220.424570280024, 8238.052623948202, 5342.385625652944, 5735.426457354398, 5741.655500669973, 5822.272215973004, 5916.49709774674, 9687.951560242154, 9872.40765554069, 9922.553485093043, 9961.053699898497, 9971.152452041657, 9503.097205765442, 9914.603415863574, 9921.0, 10070.295079344376, 10094.394336339843, 6884.345987131214, 7133.2153463117975, 7232.349088582053, 7502.849943001219, 7504.437167212372, 8240.011198656199, 8241.134680858664, 8243.793905152419, 8339.332853371594, 8345.624446900025, 7270.418366530559, 7435.405167586689, 7453.366463850417, 7560.886586701314, 7567.167611562613, 7207.9279207207255, 7268.41852651776, 7280.344768970818, 7420.5176663516695, 7437.962810186131, 6926.480513961373, 6938.722451102101, 6995.090638217, 6998.825029374103, 7036.964815175891, 4573.839915602938, 4740.834070807505, 4755.690880092783, 4883.316335713074, 4929.112759703288, 4825.6139508838505, 4868.269759536098, 4938.185199442052, 4987.501249874984, 5130.307408499954], "value": 4482.4810999451, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.74009611637865, 305.65003071483153, 313.3763810018064, 318.12991468334104, 318.2029017311634, 4069.491313585802, 5025.7235852028125, 5070.391553013639, 5085.720285384303, 5135.409427915795, 4962.727050012248, 4963.379577552806, 5049.722265275408, 5069.79720811168, 5097.286379906816, 3422.863085476578, 3906.45309656649, 3915.353966595508, 3920.7255492115564, 3927.429028388653, 3537.734669899755, 3692.612275711052, 3944.7633142011573, 3946.5461471930666, 3965.762054276738, 3072.4008818280445, 3852.4028775539696, 3884.9028774280587, 3908.9804550977206, 3920.1571662092642, 3814.618538146191, 3831.750936189139, 3844.384898416254, 3845.9423108653255, 3858.536975562922, 2758.3104223944015, 3167.228674909454, 3215.565898603682, 3325.517799918998, 3339.5825521809775, 4864.537868902448, 4899.0, 4899.363082799249, 4900.97079613278, 4915.188993816052, 3736.869209577652, 3833.731638785286, 4140.275451795954, 4385.934210986838, 4413.977930110361, 4471.932921006188, 5320.175372817181, 5350.314650884998, 5355.0, 5378.650387724785, 3208.4385232584436, 3407.4207384744404, 3528.6591095383847, 3662.7802331860175, 3675.3935600625864, 4674.99487610162, 4898.926516102261, 5152.4847515248175, 5554.361248456398, 5594.021046316879, 3229.951550726741, 3385.830708464567, 3606.531150950412, 3766.6610005099674, 3768.378217594121, 3240.9675903241136, 3483.9303213936087, 3757.5866654667966, 3803.91588397311, 3815.73289869712, 3998.4202290668272, 3998.9202915213314, 4047.9677682190854, 4326.307790753464, 4493.348464472699, 4415.735055896656, 4835.975820120877, 4944.505549445099, 5121.615878809104, 5393.946060539422, 1097.5938902605926, 1134.551852018448, 1301.537954026326, 1310.4037662863375, 1315.3423288355823, 747.7794050755041, 783.9137694853562, 791.2996997657115, 799.7840583042662, 800.2237829305565, 3874.9225015499533, 4090.1206240657807, 4173.561776013536, 4180.853670121592, 4195.3916682081535, 4100.462326627515, 4390.102532328711, 4561.221123761736, 4591.632669386441, 4633.351330813689, 4160.791960401968, 4162.5629308922735, 4164.875053748392, 4183.247015537172, 4198.538160802309, 2208.436848603596, 2225.3880182949492, 2353.2349489349945, 2441.2920253126395, 2494.6881639795024, 4118.91762164755, 4196.426340122454, 4481.820727170942, 4824.710517368969, 4842.93912861061, 4031.314676505001, 4266.360045993126, 4628.866751554204, 4641.95358046422, 4732.63368315842, 4325.7837108145095, 4338.110687309094, 4499.510597071568, 4662.836800711959, 4723.692959957661, 3992.7041950878943, 4076.6331030207425, 4335.891602709958, 4625.658559017847, 4640.215870477687, 3988.3020471417967, 4028.8388464461095, 4301.548337424525, 4490.350693091092, 4577.382053422745, 4273.807678654406, 4458.621017213573, 4920.76105372062, 5143.685341596221, 5526.226328313959, 3438.3123375325536, 3613.1689711366007, 3708.9629103708617, 3709.9814500927323, 3717.5353080864893, 5033.602318435115, 5109.397874498395, 5287.514868012028, 5511.917321240105, 5525.778968841201, 4962.826301079445, 5326.760295786621, 5328.626996110235, 5345.54562862161, 5372.758225879923, 4495.056038231877, 5308.920366194433, 5338.412774594869, 5339.546138578109, 5368.677879327252, 4544.296652964166, 4819.95180048195, 5214.791408343775, 5242.58059355243, 5260.579153667621, 4706.458757242824, 5012.548870601663, 5068.467810879804, 5317.0858490942455, 5318.122509785842, 4740.928886066781, 5202.531772140374, 5231.529162375405, 5276.551493122974, 5296.735163241746], "value": 3684.057356777876, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.1919544993783, 649.2176926803203, 714.0610097721495, 731.104397113536, 749.1160430691783, 13339.93280537557, 14858.182799945998, 14954.626134346634, 15176.468823591176, 17092.48722538323, 16231.107289099094, 16366.181690915466, 16404.769642276817, 16413.507594772145, 16437.753433698475, 706.2161001288565, 731.989020164698, 885.3625389719408, 899.0110878034143, 899.9325050621229, 873.8033942362949, 902.4946030223064, 903.336048004716, 905.1988989744091, 907.4011152639259, 797.3342259213564, 928.9071092890724, 940.463935556733, 940.7459985803829, 941.7316064921499, 737.3511310047178, 898.9775255618662, 930.3394589841221, 935.0322416299132, 938.4228699349885, 691.7751730687546, 691.989620155698, 692.6398272898115, 692.882210024297, 693.639307560066, 14951.551453456304, 14975.700485990219, 14980.0, 14990.325435355435, 14991.550253492413, 12017.93991030048, 12046.337451440168, 12151.756964860742, 12289.93855030728, 12301.384930753517, 10797.082248008928, 10887.0, 10968.23222374434, 11024.0, 11413.60052398162, 3978.840846366171, 4067.0, 4120.670346372283, 4122.196171746511, 4123.9381409278885, 86152.70770938504, 88956.0, 88979.55510222407, 89340.55329723375, 90856.0, 9220.308476864262, 9247.352685312098, 9382.390144640576, 9468.95265523668, 9692.9030709692, 20664.380068597966, 20689.68965465519, 20690.275840345515, 20703.58592828135, 20737.79262207389, 7217.350438460564, 7239.167495738057, 7282.526635768658, 7399.852002960019, 7694.807629809187, 4013.1973605278476, 4063.1061166543313, 4079.265732168238, 4079.6124368184974, 4085.632293093636, 2689.462107578492, 3390.4744764561533, 3392.8982130536124, 3400.4559270516597, 3405.063607507955, 611.2939554814196, 616.4174854762207, 618.8391018335175, 628.5631486117105, 641.403494749887, 514.8429728932637, 589.2339958054512, 592.4756590417426, 620.5997131849931, 622.4802290087737, 7839.764807055797, 7950.6422211000645, 8033.5179889205665, 8079.070906845671, 8195.180481951757, 494.658685506999, 549.2146230889786, 581.1950448628679, 585.7569108819878, 588.8037619678623, 3819.9427008594894, 3873.903152421212, 3875.844966201377, 3892.591277915778, 4051.554329023805, 86421.56789216014, 89847.0, 92487.61268580977, 92548.0, 92665.0, 81234.0, 81755.0, 81878.0, 82517.894106792, 82940.58529707433, 53728.92542149057, 72982.0807167718, 73620.0, 74096.51806963938, 74418.0, 7243.8409854425145, 7488.475806693697, 7560.8487830244185, 7652.196519365387, 7663.386929045551, 35906.38421270997, 39391.0, 44964.55035449604, 46670.76664616655, 47104.7644761774, 63951.360486394544, 65067.67466162639, 65187.67406162939, 65360.67319663371, 65540.67229663821, 10242.743931401774, 10251.7437064074, 10892.074173695008, 11159.776804464027, 11177.55289788432, 11765.294082355085, 12010.939945300217, 12234.510619575118, 12723.80914286268, 12936.41786119608, 7672.6547305372505, 11181.273217240854, 11242.0, 11397.031252343311, 11714.23857449263, 9501.477418742035, 11203.719907002063, 11367.886321137014, 11400.030997365317, 11523.884761152281, 10471.738206544896, 10584.941505849354, 11037.72405689832, 11094.944525277322, 11451.942740286246, 9183.83111478789, 9282.72151835446, 9420.057994200524, 9443.763905902408, 9703.17523010552, 8748.868766968628, 9551.522423878918, 9755.707328780149, 9800.460974646463, 9887.30788844774], "value": 6822.588713335311, "unit": "score"}], "name": "dromaeo_css", "value": 6988.910416916123, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 147.0, 116.0, 170.0, 121.0, 131.0, 143.0, 117.0, 182.0, 143.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 185.0, 184.0, 183.0, 183.0, 182.0, 183.0, 183.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 77.0, 111.0, 81.0, 74.0, 64.0, 57.0, 96.0, 56.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 134.0, 101.0, 73.0, 69.0, 111.0, 55.0, 66.0, 114.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 88.0, 89.0, 87.0, 90.0, 83.0, 91.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 101.0, 95.0, 95.0, 96.0, 94.0, 99.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 86.0, 83.0, 87.0, 84.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 59.0, 56.0, 58.0, 56.0, 55.0, 77.0, 57.0, 55.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 65.0, 70.0, 73.0, 75.0, 71.0, 70.0, 71.0, 71.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 103.0, 108.0, 87.0, 84.0, 85.0, 83.0, 87.0, 86.0, 88.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 140.0, 147.0, 146.0, 144.0, 149.0, 139.0, 137.0, 178.0, 149.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 224.0, 196.0, 169.0, 165.0, 165.0, 162.0, 163.0, 162.0, 164.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 68.0, 79.0, 84.0, 85.0, 86.0, 68.0, 66.0], "value": 73.4, "unit": "score"}], "name": "kraken", "value": 1489.8999999999999, "alertThreshold": 2.0}]} 16:45:38 INFO - Return code: 0 16:45:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:45:38 ERROR - # TBPL SUCCESS # 16:45:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:38 INFO - 2016-05-26 16:45:38.360 screenresolution[1944:12588] starting screenresolution argv=screenresolution get 16:45:38 INFO - 2016-05-26 16:45:38.363 screenresolution[1944:12588] Display 0: 1600x1200x32@60 16:45:38 INFO - 2016-05-26 16:45:38.369 screenresolution[1945:12592] starting screenresolution argv=screenresolution list 16:45:38 INFO - Available Modes on Display 0 16:45:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:38 INFO - Intel Iris: 16:45:38 INFO - Chipset Model: Intel Iris 16:45:38 INFO - Type: GPU 16:45:38 INFO - Bus: Built-In 16:45:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:38 INFO - Vendor: Intel (0x8086) 16:45:38 INFO - Device ID: 0x0a2e 16:45:38 INFO - Revision ID: 0x0009 16:45:38 INFO - Displays: 16:45:38 INFO - Display: 16:45:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:38 INFO - Display Serial Number: 1600x1200 60 16:45:38 INFO - Main Display: Yes 16:45:38 INFO - Mirror: Off 16:45:38 INFO - Online: Yes 16:45:38 INFO - Rotation: Supported 16:45:38 INFO - Return code: 0 16:45:38 INFO - Running post-action listener: _resource_record_post_action 16:45:38 INFO - ##### 16:45:38 INFO - ##### Finished run-tests step (success) 16:45:38 INFO - ##### 16:45:38 INFO - Running post-run listener: _resource_record_post_run 16:45:38 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 2506752; Write bytes: 698044416; Read time: 24; Write time: 1924 16:45:38 INFO - TinderboxPrint: CPU usage
53.7% 16:45:38 INFO - TinderboxPrint: I/O read bytes / time
2,506,752 / 24 16:45:38 INFO - TinderboxPrint: I/O write bytes / time
698,044,416 / 1,924 16:45:38 INFO - TinderboxPrint: CPU idle
2,243.0 (46.3%) 16:45:38 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 16:45:38 INFO - TinderboxPrint: CPU user
1,634.5 (33.7%) 16:45:38 INFO - TinderboxPrint: Swap in / out
400,154,624 / 0 16:45:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393122304; Write bytes: 387795968; Read time: 29593; Write time: 835 16:45:38 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 2502656; Write bytes: 297038848; Read time: 24; Write time: 1055 16:45:39 INFO - Running post-run listener: _upload_blobber_files 16:45:39 INFO - Blob upload gear active. 16:45:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:45:39 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:45:39 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:45:39 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:45:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:45:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:45:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:45:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:45:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:40 INFO - (blobuploader) - INFO - Done attempting. 16:45:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:45:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:45:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:45:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:41 INFO - (blobuploader) - INFO - Done attempting. 16:45:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:45:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:45:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:45:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:44 INFO - (blobuploader) - INFO - Done attempting. 16:45:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:45:44 INFO - Return code: 0 16:45:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:45:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:45:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaabcbfac84bb31c869a29596822e1fc2fe1df1c8339a321cdd0161c9d67f254b82d0332aa8f1d67fcf590db7e38eca2476527d6f1dec46f9b0e8c968dfb6284", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/654ef9e7adc06a71ee21c6c587d379edcf1ff81a4886e1ad3196d36a622717c5f675f020e6a49bde93717e838ca94863d0c772b378e1a921a9c6b4fb0ea4fe6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88f8b4f81c1339f040f95fa3b50b6d78c5ce223ba24274259a5d78970c58c8bd80ffcd11695e3a828d9acc2a45c33d30e7d68cace2e78e5112bf60727d279f85"} 16:45:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:45:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:45:44 INFO - Contents: 16:45:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaabcbfac84bb31c869a29596822e1fc2fe1df1c8339a321cdd0161c9d67f254b82d0332aa8f1d67fcf590db7e38eca2476527d6f1dec46f9b0e8c968dfb6284", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/654ef9e7adc06a71ee21c6c587d379edcf1ff81a4886e1ad3196d36a622717c5f675f020e6a49bde93717e838ca94863d0c772b378e1a921a9c6b4fb0ea4fe6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88f8b4f81c1339f040f95fa3b50b6d78c5ce223ba24274259a5d78970c58c8bd80ffcd11695e3a828d9acc2a45c33d30e7d68cace2e78e5112bf60727d279f85"} 16:45:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:45:44 INFO - Copying logs to upload dir... 16:45:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:45:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.300206 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-26 16:45:44.449307) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 16:45:44.452595) ========= 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.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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/aaabcbfac84bb31c869a29596822e1fc2fe1df1c8339a321cdd0161c9d67f254b82d0332aa8f1d67fcf590db7e38eca2476527d6f1dec46f9b0e8c968dfb6284", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/654ef9e7adc06a71ee21c6c587d379edcf1ff81a4886e1ad3196d36a622717c5f675f020e6a49bde93717e838ca94863d0c772b378e1a921a9c6b4fb0ea4fe6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88f8b4f81c1339f040f95fa3b50b6d78c5ce223ba24274259a5d78970c58c8bd80ffcd11695e3a828d9acc2a45c33d30e7d68cace2e78e5112bf60727d279f85"} build_url:https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010966 build_url: 'https://queue.taskcluster.net/v1/task/GMjTrGKDTGqkJ4u186gv5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaabcbfac84bb31c869a29596822e1fc2fe1df1c8339a321cdd0161c9d67f254b82d0332aa8f1d67fcf590db7e38eca2476527d6f1dec46f9b0e8c968dfb6284", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/654ef9e7adc06a71ee21c6c587d379edcf1ff81a4886e1ad3196d36a622717c5f675f020e6a49bde93717e838ca94863d0c772b378e1a921a9c6b4fb0ea4fe6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88f8b4f81c1339f040f95fa3b50b6d78c5ce223ba24274259a5d78970c58c8bd80ffcd11695e3a828d9acc2a45c33d30e7d68cace2e78e5112bf60727d279f85"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 16:45:44.483275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:45:44.483613) ========= 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.F7L5XwFFf0/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.3yRJnuVEek/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 16:45:44.532167) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 16:45:44.532523) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 16:45:44.532920) ========= ========= Total master_lag: 0.18 =========