builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1460597416.95 results: success (0) buildid: 20160413163939 builduid: ab43773f5f1f4c22aca646ecdfe2cf6e revision: 8630367f5e3f750e23cd04f309a1b279ff07e4a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:16.951041) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:16.951570) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:16.951871) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005775 basedir: '/builds/slave/test' ========= master_lag: 0.40 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.361404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.361687) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.410573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.410832) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005641 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.465129) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.465508) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.465897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.466187) ========= 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.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-13 18:30:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.28M=0.001s 2016-04-13 18:30:17 (8.28 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099190 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.581304) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.581625) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.020127 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:17.630023) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-13 18:30:17.630394) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8630367f5e3f750e23cd04f309a1b279ff07e4a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8630367f5e3f750e23cd04f309a1b279ff07e4a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-13 18:30:17,695 truncating revision to first 12 chars 2016-04-13 18:30:17,696 Setting DEBUG logging. 2016-04-13 18:30:17,696 attempt 1/10 2016-04-13 18:30:17,696 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8630367f5e3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-13 18:30:19,071 unpacking tar archive at: mozilla-central-8630367f5e3f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.615103 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-13 18:30:19.394513) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:19.394893) ========= echo 8630367f5e3f750e23cd04f309a1b279ff07e4a2 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'8630367f5e3f750e23cd04f309a1b279ff07e4a2'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 8630367f5e3f750e23cd04f309a1b279ff07e4a2 program finished with exit code 0 elapsedTime=0.005257 script_repo_revision: '8630367f5e3f750e23cd04f309a1b279ff07e4a2' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:19.418980) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:19.419311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-13 18:30:19.432827) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-13 18:30:19.433180) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:30:19 INFO - MultiFileLogger online at 20160413 18:30:19 in /builds/slave/test 18:30:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:30:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:30:19 INFO - {'append_to_log': False, 18:30:19 INFO - 'base_work_dir': '/builds/slave/test', 18:30:19 INFO - 'blob_upload_branch': 'Firefox', 18:30:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:30:19 INFO - 'branch': 'Firefox', 18:30:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:30:19 INFO - 'config_files': ('talos/mac_config.py',), 18:30:19 INFO - 'default_actions': ('clobber', 18:30:19 INFO - 'read-buildbot-config', 18:30:19 INFO - 'download-and-extract', 18:30:19 INFO - 'populate-webroot', 18:30:19 INFO - 'create-virtualenv', 18:30:19 INFO - 'install', 18:30:19 INFO - 'run-tests'), 18:30:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:30:19 INFO - 'download_minidump_stackwalk': True, 18:30:19 INFO - 'download_symbols': 'ondemand', 18:30:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:30:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:30:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:30:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:30:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:30:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:30:19 INFO - 'installer_path': 'installer.exe', 18:30:19 INFO - 'log_level': 'info', 18:30:19 INFO - 'log_name': 'talos', 18:30:19 INFO - 'log_to_console': True, 18:30:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:30:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:30:19 INFO - 'opt_config_files': (), 18:30:19 INFO - 'pip_index': False, 18:30:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:19 INFO - 'cmd': ('bash', 18:30:19 INFO - '-c', 18:30:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:19 INFO - 'enabled': True, 18:30:19 INFO - 'halt_on_failure': False, 18:30:19 INFO - 'name': 'check_screen_resolution'},), 18:30:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:19 INFO - 'cmd': ('bash', 18:30:19 INFO - '-c', 18:30:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:19 INFO - 'enabled': True, 18:30:19 INFO - 'halt_on_failure': False, 18:30:19 INFO - 'name': 'check_screen_resolution'},), 18:30:19 INFO - 'run_cmd_checks_enabled': True, 18:30:19 INFO - 'sps_profile': False, 18:30:19 INFO - 'sps_profile_interval': 0, 18:30:19 INFO - 'suite': 'dromaeojs', 18:30:19 INFO - 'system_bits': '32', 18:30:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:30:19 INFO - 'title': 't-yosemite-r7-0127', 18:30:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:30:19 INFO - 'use_talos_json': True, 18:30:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:30:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:30:19 INFO - 'work_dir': 'build'} 18:30:19 INFO - ##### 18:30:19 INFO - ##### Running clobber step. 18:30:19 INFO - ##### 18:30:19 INFO - Running pre-action listener: _resource_record_pre_action 18:30:19 INFO - Running main action method: clobber 18:30:19 INFO - rmtree: /builds/slave/test/build 18:30:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:30:21 INFO - Running post-action listener: _resource_record_post_action 18:30:21 INFO - ##### 18:30:21 INFO - ##### Running read-buildbot-config step. 18:30:21 INFO - ##### 18:30:21 INFO - Running pre-action listener: _resource_record_pre_action 18:30:21 INFO - Running main action method: read_buildbot_config 18:30:21 INFO - Using buildbot properties: 18:30:21 INFO - { 18:30:21 INFO - "project": "", 18:30:21 INFO - "product": "firefox", 18:30:21 INFO - "script_repo_revision": "production", 18:30:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:30:21 INFO - "repository": "", 18:30:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:30:21 INFO - "buildid": "20160413163939", 18:30:21 INFO - "pgo_build": "False", 18:30:21 INFO - "basedir": "/builds/slave/test", 18:30:21 INFO - "buildnumber": 217, 18:30:21 INFO - "slavename": "t-yosemite-r7-0127", 18:30:21 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:30:21 INFO - "platform": "yosemite_r7", 18:30:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:30:21 INFO - "branch": "mozilla-central", 18:30:21 INFO - "repo_path": "mozilla-central", 18:30:21 INFO - "revision": "8630367f5e3f750e23cd04f309a1b279ff07e4a2", 18:30:21 INFO - "stage_platform": "macosx64", 18:30:21 INFO - "builduid": "ab43773f5f1f4c22aca646ecdfe2cf6e", 18:30:21 INFO - "slavebuilddir": "test" 18:30:21 INFO - } 18:30:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 18:30:21 INFO - Running post-action listener: _resource_record_post_action 18:30:21 INFO - ##### 18:30:21 INFO - ##### Running download-and-extract step. 18:30:21 INFO - ##### 18:30:21 INFO - Running pre-action listener: _resource_record_pre_action 18:30:21 INFO - Running main action method: download_and_extract 18:30:21 INFO - mkdir: /builds/slave/test/build/tests 18:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:21 INFO - https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:30:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:30:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:30:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 18:30:23 INFO - Downloaded 1199 bytes. 18:30:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:30:23 INFO - Using the following test package requirements: 18:30:23 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:30:23 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 18:30:23 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 18:30:23 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'jsshell-mac.zip'], 18:30:23 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 18:30:23 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:30:23 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 18:30:23 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 18:30:23 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 18:30:23 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:30:23 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 18:30:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:23 INFO - https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:30:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:30:24 INFO - Downloaded 18031194 bytes. 18:30:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:30:27 INFO - Return code: 0 18:30:27 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:27 INFO - https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:30:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:30:42 INFO - Downloaded 18031194 bytes. 18:30:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:30:46 INFO - Return code: 0 18:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:46 INFO - https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:30:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:30:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:30:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:30:47 INFO - Downloaded 10841076 bytes. 18:30:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:30:48 INFO - Return code: 0 18:30: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')]} 18:30:48 INFO - https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:30:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:30:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:30:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 18:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 18:30:51 INFO - Downloaded 109395358 bytes. 18:30:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:30:51 INFO - mkdir: /builds/slave/test/properties 18:30:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:51 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:51 INFO - Contents: 18:30:51 INFO - build_url:https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:30:52 INFO - Running post-action listener: _resource_record_post_action 18:30:52 INFO - Running post-action listener: set_extra_try_arguments 18:30:52 INFO - ##### 18:30:52 INFO - ##### Running populate-webroot step. 18:30:52 INFO - ##### 18:30:52 INFO - Running pre-action listener: _resource_record_pre_action 18:30:52 INFO - Running main action method: populate_webroot 18:30:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:52 INFO - '--tppagecycles', 18:30:52 INFO - '7'], 18:30:52 INFO - 'tests': ['tsvgm']}}, 18:30:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['damp', 'tps']}, 18:30:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'tests': ['damp', 'tps']}, 18:30:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['dromaeo_dom']}, 18:30:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['a11yr', 18:30:52 INFO - 'ts_paint', 18:30:52 INFO - 'tpaint', 18:30:52 INFO - 'sessionrestore', 18:30:52 INFO - 'sessionrestore_no_auto_restore', 18:30:52 INFO - 'tabpaint']}, 18:30:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['tsvgx', 18:30:52 INFO - 'tsvgr_opacity', 18:30:52 INFO - 'tart', 18:30:52 INFO - 'tscrollx', 18:30:52 INFO - 'cart']}, 18:30:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:52 INFO - 'tsvgr_opacity', 18:30:52 INFO - 'tart', 18:30:52 INFO - 'tscrollx', 18:30:52 INFO - 'cart']}, 18:30:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'talos_options': ['--disable-e10s'], 18:30:52 INFO - 'tests': ['tp5o']}, 18:30:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'tests': ['tp5o']}, 18:30:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'talos_options': ['--disable-e10s', 18:30:52 INFO - '--xperf_path', 18:30:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:52 INFO - 'tests': ['tp5n']}, 18:30:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:52 INFO - 'talos_options': ['--xperf_path', 18:30:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:52 INFO - 'tests': ['tp5n']}}, 18:30:52 INFO - 'talos.zip': {'path': '', 18:30:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:52 INFO - Running post-action listener: _resource_record_post_action 18:30:52 INFO - ##### 18:30:52 INFO - ##### Running create-virtualenv step. 18:30:52 INFO - ##### 18:30:52 INFO - Running pre-action listener: _resource_record_pre_action 18:30:52 INFO - Running main action method: create_virtualenv 18:30:52 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30: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 18:30:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:52 INFO - Using real prefix '/tools/python27' 18:30:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:56 INFO - Installing pip.................done. 18:30:56 INFO - Return code: 0 18:30:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:30: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 18:30: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 18:30:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:30:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:56 INFO - 'HOME': '/Users/cltbld', 18:30:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:56 INFO - 'LOGNAME': 'cltbld', 18:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:56 INFO - 'MOZ_NO_REMOTE': '1', 18:30:56 INFO - 'NO_EM_RESTART': '1', 18:30:56 INFO - 'PAGER': '/bin/cat', 18:30:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:56 INFO - 'PWD': '/builds/slave/test', 18:30:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:56 INFO - 'SHELL': '/bin/bash', 18:30:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:30:56 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:30:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:56 INFO - 'USER': 'cltbld', 18:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:56 INFO - 'XPC_FLAGS': '0x0', 18:30:56 INFO - 'XPC_SERVICE_NAME': '0', 18:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30: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 18:30:56 INFO - Cleaning up... 18:30:56 INFO - Return code: 0 18:30:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:30: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>=0.7.1'] in /builds/slave/test/build 18:30: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>=0.7.1 18:30:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:30:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:56 INFO - 'HOME': '/Users/cltbld', 18:30:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:56 INFO - 'LOGNAME': 'cltbld', 18:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:56 INFO - 'MOZ_NO_REMOTE': '1', 18:30:56 INFO - 'NO_EM_RESTART': '1', 18:30:56 INFO - 'PAGER': '/bin/cat', 18:30:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:56 INFO - 'PWD': '/builds/slave/test', 18:30:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:56 INFO - 'SHELL': '/bin/bash', 18:30:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:30:56 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:30:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:56 INFO - 'USER': 'cltbld', 18:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:56 INFO - 'XPC_FLAGS': '0x0', 18:30:56 INFO - 'XPC_SERVICE_NAME': '0', 18:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:56 INFO - Downloading/unpacking psutil>=0.7.1 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:59 INFO - Installing collected packages: psutil 18:30:59 INFO - Running setup.py install for psutil 18:30:59 INFO - building 'psutil._psutil_osx' extension 18:30:59 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 18:30:59 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 18:30:59 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 18:30:59 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 18:31:00 INFO - building 'psutil._psutil_posix' extension 18:31: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 18:31:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:31:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:31:00 INFO - ^ 18:31:00 INFO - 1 warning generated. 18:31: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 18:31:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:00 INFO - Successfully installed psutil 18:31:00 INFO - Cleaning up... 18:31:00 INFO - Return code: 0 18:31:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31: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.0.0'] in /builds/slave/test/build 18:31: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.0.0 18:31:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:00 INFO - 'HOME': '/Users/cltbld', 18:31:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:00 INFO - 'LOGNAME': 'cltbld', 18:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:00 INFO - 'MOZ_NO_REMOTE': '1', 18:31:00 INFO - 'NO_EM_RESTART': '1', 18:31:00 INFO - 'PAGER': '/bin/cat', 18:31:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:00 INFO - 'PWD': '/builds/slave/test', 18:31:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:00 INFO - 'SHELL': '/bin/bash', 18:31:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:00 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:00 INFO - 'USER': 'cltbld', 18:31:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:00 INFO - 'XPC_FLAGS': '0x0', 18:31:00 INFO - 'XPC_SERVICE_NAME': '0', 18:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:31:03 INFO - Installing collected packages: mozsystemmonitor 18:31:03 INFO - Running setup.py install for mozsystemmonitor 18:31:03 INFO - Successfully installed mozsystemmonitor 18:31:03 INFO - Cleaning up... 18:31:03 INFO - Return code: 0 18:31:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31: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 18:31: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 18:31:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:03 INFO - 'HOME': '/Users/cltbld', 18:31:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:03 INFO - 'LOGNAME': 'cltbld', 18:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:03 INFO - 'MOZ_NO_REMOTE': '1', 18:31:03 INFO - 'NO_EM_RESTART': '1', 18:31:03 INFO - 'PAGER': '/bin/cat', 18:31:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:03 INFO - 'PWD': '/builds/slave/test', 18:31:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:03 INFO - 'SHELL': '/bin/bash', 18:31:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:03 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:03 INFO - 'USER': 'cltbld', 18:31:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:03 INFO - 'XPC_FLAGS': '0x0', 18:31:03 INFO - 'XPC_SERVICE_NAME': '0', 18:31:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:06 INFO - Downloading docopt-0.6.1.tar.gz 18:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:07 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:07 INFO - Running setup.py install for blobuploader 18:31:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:07 INFO - Running setup.py install for requests 18:31:07 INFO - Running setup.py install for docopt 18:31:07 INFO - Successfully installed blobuploader requests docopt 18:31:07 INFO - Cleaning up... 18:31:07 INFO - Return code: 0 18:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:07 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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:08 INFO - 'HOME': '/Users/cltbld', 18:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:08 INFO - 'LOGNAME': 'cltbld', 18:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:08 INFO - 'MOZ_NO_REMOTE': '1', 18:31:08 INFO - 'NO_EM_RESTART': '1', 18:31:08 INFO - 'PAGER': '/bin/cat', 18:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:08 INFO - 'PWD': '/builds/slave/test', 18:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:08 INFO - 'SHELL': '/bin/bash', 18:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:08 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:08 INFO - 'USER': 'cltbld', 18:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:08 INFO - 'XPC_FLAGS': '0x0', 18:31:08 INFO - 'XPC_SERVICE_NAME': '0', 18:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-4UyEsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-jzSkth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-r2vVSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-_nqQH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9nPzt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-h9eAO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:08 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-4tGtNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3uSQlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-njM6v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Iu1Bvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GI9EYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-tLQm3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-fixtf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-eVbRmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vav30m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-eanzHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:09 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-BfQTdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:10 INFO - Running setup.py install for manifestparser 18:31:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:10 INFO - Running setup.py install for mozcrash 18:31:10 INFO - Running setup.py install for mozdebug 18:31:10 INFO - Running setup.py install for mozdevice 18:31:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:10 INFO - Running setup.py install for mozfile 18:31:10 INFO - Running setup.py install for mozhttpd 18:31:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:10 INFO - Running setup.py install for mozinfo 18:31:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Running setup.py install for mozInstall 18:31:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Running setup.py install for mozleak 18:31:11 INFO - Running setup.py install for mozlog 18:31:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Running setup.py install for moznetwork 18:31:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Running setup.py install for mozprocess 18:31:11 INFO - Running setup.py install for mozprofile 18:31:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:11 INFO - Running setup.py install for mozrunner 18:31:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:12 INFO - Running setup.py install for mozscreenshot 18:31:12 INFO - Running setup.py install for moztest 18:31:12 INFO - Running setup.py install for mozversion 18:31:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:12 INFO - Cleaning up... 18:31:12 INFO - Return code: 0 18:31:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:12 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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:12 INFO - 'HOME': '/Users/cltbld', 18:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:12 INFO - 'LOGNAME': 'cltbld', 18:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:12 INFO - 'MOZ_NO_REMOTE': '1', 18:31:12 INFO - 'NO_EM_RESTART': '1', 18:31:12 INFO - 'PAGER': '/bin/cat', 18:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:12 INFO - 'PWD': '/builds/slave/test', 18:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:12 INFO - 'SHELL': '/bin/bash', 18:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:12 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:12 INFO - 'USER': 'cltbld', 18:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:12 INFO - 'XPC_FLAGS': '0x0', 18:31:12 INFO - 'XPC_SERVICE_NAME': '0', 18:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sxMIh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31: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)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-fJdKAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31: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)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-uWQ5C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31: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)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-q7v07y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Eu02Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-tw_HAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:13 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-FAzxsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31: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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HOm3Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31: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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-61dcp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31: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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GMUDp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-6NwuHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31: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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-DZ05y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31: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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-gWMKec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vgasvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:14 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)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-evZ7EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-cdxHCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:14 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-bgZua5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:17 INFO - Downloading blessings-1.6.tar.gz 18:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:18 INFO - Installing collected packages: blessings 18:31:18 INFO - Running setup.py install for blessings 18:31:18 INFO - Successfully installed blessings 18:31:18 INFO - Cleaning up... 18:31:18 INFO - Return code: 0 18:31:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:18 INFO - Reading from file tmpfile_stdout 18:31:18 INFO - Current package versions: 18:31:18 INFO - blessings == 1.6 18:31:18 INFO - blobuploader == 1.2.4 18:31:18 INFO - docopt == 0.6.1 18:31:18 INFO - manifestparser == 1.1 18:31:18 INFO - mozInstall == 1.12 18:31:18 INFO - mozcrash == 0.17 18:31:18 INFO - mozdebug == 0.1 18:31:18 INFO - mozdevice == 0.48 18:31:18 INFO - mozfile == 1.2 18:31:18 INFO - mozhttpd == 0.7 18:31:18 INFO - mozinfo == 0.9 18:31:18 INFO - mozleak == 0.1 18:31:18 INFO - mozlog == 3.1 18:31:18 INFO - moznetwork == 0.27 18:31:18 INFO - mozprocess == 0.22 18:31:18 INFO - mozprofile == 0.28 18:31:18 INFO - mozrunner == 6.11 18:31:18 INFO - mozscreenshot == 0.1 18:31:18 INFO - mozsystemmonitor == 0.0 18:31:18 INFO - moztest == 0.7 18:31:18 INFO - mozversion == 1.4 18:31:18 INFO - psutil == 3.1.1 18:31:18 INFO - requests == 1.2.3 18:31:18 INFO - wsgiref == 0.1.2 18:31:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31: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 18:31: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 18:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:18 INFO - 'HOME': '/Users/cltbld', 18:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:18 INFO - 'LOGNAME': 'cltbld', 18:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:18 INFO - 'MOZ_NO_REMOTE': '1', 18:31:18 INFO - 'NO_EM_RESTART': '1', 18:31:18 INFO - 'PAGER': '/bin/cat', 18:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:18 INFO - 'PWD': '/builds/slave/test', 18:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:18 INFO - 'SHELL': '/bin/bash', 18:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:18 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:18 INFO - 'USER': 'cltbld', 18:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:18 INFO - 'XPC_FLAGS': '0x0', 18:31:18 INFO - 'XPC_SERVICE_NAME': '0', 18:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31: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)) 18:31:18 INFO - Cleaning up... 18:31:18 INFO - Return code: 0 18:31:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31: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 18:31: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 18:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:18 INFO - 'HOME': '/Users/cltbld', 18:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:18 INFO - 'LOGNAME': 'cltbld', 18:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:18 INFO - 'MOZ_NO_REMOTE': '1', 18:31:18 INFO - 'NO_EM_RESTART': '1', 18:31:18 INFO - 'PAGER': '/bin/cat', 18:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:18 INFO - 'PWD': '/builds/slave/test', 18:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:18 INFO - 'SHELL': '/bin/bash', 18:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:18 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:18 INFO - 'USER': 'cltbld', 18:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:18 INFO - 'XPC_FLAGS': '0x0', 18:31:18 INFO - 'XPC_SERVICE_NAME': '0', 18:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:19 INFO - Downloading/unpacking jsonschema 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:21 INFO - Downloading/unpacking functools32 (from jsonschema) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:21 INFO - warning: no files found matching '*.txt' 18:31:21 INFO - no previously-included directories found matching 'build' 18:31:21 INFO - no previously-included directories found matching 'dist' 18:31:21 INFO - no previously-included directories found matching '.git*' 18:31:21 INFO - Installing collected packages: jsonschema, functools32 18:31:22 INFO - Running setup.py install for functools32 18:31:22 INFO - warning: no files found matching '*.txt' 18:31:22 INFO - no previously-included directories found matching 'build' 18:31:22 INFO - no previously-included directories found matching 'dist' 18:31:22 INFO - no previously-included directories found matching '.git*' 18:31:22 INFO - Successfully installed jsonschema functools32 18:31:22 INFO - Cleaning up... 18:31:22 INFO - Return code: 0 18:31:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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 0x10def9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df09420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8013473a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, '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.UNREqd8UOH/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.ahAIGv5EgP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 18:31: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 18:31: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 18:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:22 INFO - 'HOME': '/Users/cltbld', 18:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:22 INFO - 'LOGNAME': 'cltbld', 18:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:22 INFO - 'MOZ_NO_REMOTE': '1', 18:31:22 INFO - 'NO_EM_RESTART': '1', 18:31:22 INFO - 'PAGER': '/bin/cat', 18:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:22 INFO - 'PWD': '/builds/slave/test', 18:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:22 INFO - 'SHELL': '/bin/bash', 18:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:31:22 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:22 INFO - 'USER': 'cltbld', 18:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:22 INFO - 'XPC_FLAGS': '0x0', 18:31:22 INFO - 'XPC_SERVICE_NAME': '0', 18:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:22 INFO - Downloading/unpacking flake8 18:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:31:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:31:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:31:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:31:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:31:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:31:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:31:26 INFO - no previously-included directories found matching 'docs/_build' 18:31:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:31:26 INFO - Running setup.py install for pep8 18:31:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:31:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:31:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:31:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:31:26 INFO - no previously-included directories found matching 'docs/_build' 18:31:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:31:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:31:26 INFO - Cleaning up... 18:31:26 INFO - Return code: 0 18:31:26 INFO - Running post-action listener: _resource_record_post_action 18:31:26 INFO - Running post-action listener: _start_resource_monitoring 18:31:26 INFO - Starting resource monitoring. 18:31:26 INFO - ##### 18:31:26 INFO - ##### Running install step. 18:31:26 INFO - ##### 18:31:26 INFO - Running pre-action listener: _resource_record_pre_action 18:31:26 INFO - Running main action method: install 18:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:27 INFO - Reading from file tmpfile_stdout 18:31:27 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:27 INFO - Reading from file tmpfile_stdout 18:31:27 INFO - Output received: 18:31:27 INFO - Usage: mozinstall [options] installer 18:31:27 INFO - Options: 18:31:27 INFO - -h, --help show this help message and exit 18:31:27 INFO - -d DEST, --destination=DEST 18:31:27 INFO - Directory to install application into. [default: 18:31:27 INFO - "/builds/slave/test"] 18:31:27 INFO - --app=APP Application being installed. [default: firefox] 18:31:27 INFO - mkdir: /builds/slave/test/build/application 18:31:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:32:00 INFO - Reading from file tmpfile_stdout 18:32:00 INFO - Output received: 18:32:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:32:00 INFO - Running post-action listener: _resource_record_post_action 18:32:00 INFO - ##### 18:32:00 INFO - ##### Running run-tests step. 18:32:00 INFO - ##### 18:32:00 INFO - Running pre-action listener: _resource_record_pre_action 18:32:00 INFO - Running main action method: run_tests 18:32:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:00 INFO - 2016-04-13 18:32:00.487 screenresolution[1927:14113] starting screenresolution argv=screenresolution get 18:32:00 INFO - 2016-04-13 18:32:00.490 screenresolution[1927:14113] Display 0: 1600x1200x32@60 18:32:00 INFO - 2016-04-13 18:32:00.496 screenresolution[1928:14115] starting screenresolution argv=screenresolution list 18:32:00 INFO - Available Modes on Display 0 18:32:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:00 INFO - Intel Iris: 18:32:00 INFO - Chipset Model: Intel Iris 18:32:00 INFO - Type: GPU 18:32:00 INFO - Bus: Built-In 18:32:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:00 INFO - Vendor: Intel (0x8086) 18:32:00 INFO - Device ID: 0x0a2e 18:32:00 INFO - Revision ID: 0x0009 18:32:00 INFO - Displays: 18:32:00 INFO - Display: 18:32:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:00 INFO - Display Serial Number: 1600x1200 60 18:32:00 INFO - Main Display: Yes 18:32:00 INFO - Mirror: Off 18:32:00 INFO - Online: Yes 18:32:00 INFO - Rotation: Supported 18:32:00 INFO - Return code: 0 18:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:00 INFO - Python 2.7.3 18:32:00 INFO - Return code: 0 18:32:00 INFO - grabbing minidump binary from tooltool 18:32: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')]} 18:32:00 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd98e00>, '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 0x10dbc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd93b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:00 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 18:32:00 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 18:32:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:00 INFO - Return code: 0 18:32:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:32:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:32:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:32:02 INFO - Return code: 0 18:32:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:32:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UNREqd8UOH/Render', 18:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:02 INFO - 'HOME': '/Users/cltbld', 18:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:02 INFO - 'LOGNAME': 'cltbld', 18:32:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:02 INFO - 'MOZ_NO_REMOTE': '1', 18:32:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:02 INFO - 'NO_EM_RESTART': '1', 18:32:02 INFO - 'PAGER': '/bin/cat', 18:32:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:02 INFO - 'PWD': '/builds/slave/test', 18:32:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:02 INFO - 'SHELL': '/bin/bash', 18:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ahAIGv5EgP/Listeners', 18:32:02 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 18:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:02 INFO - 'USER': 'cltbld', 18:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:02 INFO - 'XPC_FLAGS': '0x0', 18:32:02 INFO - 'XPC_SERVICE_NAME': '0', 18:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:32:02 INFO - mozversion application_buildid: 20160413163939 18:32:02 INFO - mozversion application_changeset: 8630367f5e3f750e23cd04f309a1b279ff07e4a2 18:32:02 INFO - mozversion application_display_name: Nightly 18:32:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:02 INFO - mozversion application_name: Firefox 18:32:02 INFO - mozversion application_remotingname: firefox 18:32:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:32:02 INFO - mozversion application_vendor: Mozilla 18:32:02 INFO - mozversion application_version: 48.0a1 18:32:02 INFO - mozversion platform_buildid: 20160413163939 18:32:02 INFO - mozversion platform_changeset: 8630367f5e3f750e23cd04f309a1b279ff07e4a2 18:32:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:32:02 INFO - mozversion platform_version: 48.0a1 18:32:02 INFO - using testdate: 1460597522 18:32:02 INFO - actual date: 1460597522 18:32:02 INFO - starting webserver on 'localhost:51823' 18:32:02 INFO - SUITE-START | Running 2 tests 18:32:02 INFO - TEST-START | dromaeo_css 18:32:02 INFO - operating with platform_type : mac_ 18:32:02 INFO - Initialising browser for dromaeo_css test... 18:32:02 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpLKFsga/profile http://localhost:51823/getInfo.html) 18:32:04 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 18:32:04 INFO - PROCESS | 1946 | colorDepth:24 18:32:04 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 18:32:04 INFO - PROCESS | 1946 | __metrics 18:32:04 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:32:04 ERROR - PROCESS | 1946 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:32:05 INFO - TEST-INFO | 1946: exit 0 18:32:05 INFO - Browser initialized. 18:32:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:32:05 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpLKFsga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:17 INFO - PROCESS | 1948 | RSS: Main: 237461504 18:32:17 INFO - PROCESS | 1948 | 18:33:08 INFO - PROCESS | 1948 | 1460597588382 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:35:18 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51823/tests/dromaeo/cssquery-ext.html) 18:35:19 INFO - PROCESS | 1948 | RSS: Main: 353841152 18:35:19 INFO - PROCESS | 1948 | 18:38:22 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-ext.html (next: http://localhost:51823/tests/dromaeo/cssquery-jquery.html) 18:38:23 INFO - PROCESS | 1948 | RSS: Main: 376410112 18:38:23 INFO - PROCESS | 1948 | 18:41:25 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51823/tests/dromaeo/cssquery-mootools.html) 18:41:26 INFO - PROCESS | 1948 | RSS: Main: 374259712 18:41:26 INFO - PROCESS | 1948 | 18:44:28 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51823/tests/dromaeo/cssquery-prototype.html) 18:44:28 INFO - PROCESS | 1948 | RSS: Main: 374214656 18:44:28 INFO - PROCESS | 1948 | 18:47:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51823/tests/dromaeo/cssquery-yui.html) 18:47:32 INFO - PROCESS | 1948 | RSS: Main: 337932288 18:47:32 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:51823/tests/dromaeo/cssquery-yui.html (next: http://localhost:51823/tests/dromaeo/cssquery-dojo.html) 18:50:35 INFO - PROCESS | 1948 | RSS: Main: 380952576 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | __start_tp_report 18:50:35 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 18:50:35 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 18:50:35 INFO - PROCESS | 1948 | |i|pagename|runs| 18:50:35 INFO - PROCESS | 1948 | |0;dojo.html;2808.7191280871907;3397.201657610461;3417.794932304062;3439.29494453637;3481;5490.835274941748;5531.446855314466;5543.279373681421;5576.86057848554;5615.157726341051;5572.916406253909;5585.720713964296;5635.408282130379;5742.712864356776;5747.281589801275;5069.214271787879;5099.980003999196;5124.103281925667;5134.2812006319145;5224.451432599561;5168.508991645787;5207.739613019353;5222.529972302492;5222.791088356461;5239.738013099349;4662.323963025359;5068.6705364151585;5158.613104017176;5181.35233095863;5210.947890521084;5076.771545280471;5083.847484575432;5108.948910510884;5145.279660847485;5146.022255771391;5066.468020857794;5093.694378337274;5118.564921981636;5125.051865404875;5137.563307118862;5431.728413579305;5509.00837849187;5527;5529.170624406331;5604.551635869121;6105.755769769204;6105.786297479567;6148.78479253224;6159.969200153971;6161.661108639023;5642.362832384869;6199.473044791198;6219.40915613016;6233.594816336924;6234.532410069218;5542.806001789918;5615.578831587647;5650.5479561634975;5674.914876276859;5716.628419152742;6280.905786413207;6286.874262514815;6296.181496405529;6298.433141017331;6309.526785491106;5982.910256346158;5986.311574169026;5995.910061349084;6007.279126504847;6011.639301641915;6035.788747393908;6065.60573562717;6080.6047606906295;6108.6759897029115;6114.631938157314;6093.878122437526;6101.3079194897555;6111.755529778848;6114.540688159484;6125.877482450415;6115.052166914135;6126.693665316717;6141.109539116803;6141.508679305645;6146.78486252979;4741.786619602126;4789.401324834395;4796.400449943757;4868;4871.415430148334;4315.244832154361;4315.956840431619;4356.847510337123;4392.670549708785;4439.622632076245;5507.284053073075;5540.556755459635;5543.334799824047;5554.055810512223;5559.444055594408;4949.975250123726;6124.479419249414;6127.128444391265;6199.597026193283;6201.658908759971;5495.752691128909;5529;5529.585281103933;5584.162375643686;5600.383957764724;5254.9211761822835;5256.106461901486;5272.815451459181;5281.471852814765;5295.285136506599;6175.413335733098;6176.845578860564;6216.968915155395;6298.08677741734;6306.369363063657;6161.722722477589;6203.379662033761;6215.098810672517;6221.440070393686;6227.159333490102;6271.96864015677;6285.5600107992;6289.056641503811;6295.5907865988565;6305.274893387319;5923.703814809156;6027.758889644365;6032.215811944419;6052.122442245762;6095.603785753912;6129.387061294014;6190.164327815687;6198.0702894566175;6198.876022479615;6218.409251121137;5939.316978547349;5977.521798256216;5983.461488466059;5992.0712289595185;6034.8793024138395;5789.797357092481;5857.677827719346;5891.469767720926;5919.437653422918;5972.343042265435;5014.84955451337;5805.883882322414;5815.476607105381;5834.7082645866685;5860.677662728591;5944.970275148597;5971.940280597139;5995.670238136944;6019.157317975574;6049.727762250797;5575.888482230252;5663.943360566342;5782.248307720138;5811.738471768866;5830 18:50:35 INFO - PROCESS | 1948 | |1;ext.html;506.17998841876164;527.1539179616715;574.1559906936802;610.5848023344126;644.5552568727578;29900.700992990063;33324.83363082287;37346.81326593367;38252.80873595635;38691.032724181874;39929.40105898414;40276;40392.59607403925;40443;40451.988700282505;8476.957615211915;9443.961164271925;9666.709998700015;9670.613175473009;9693.079157480028;8617.224449799516;11495.770084598304;11867.465964031639;11870.465829037714;11879;11540.480678369451;11667;11668;11686.298822070661;11687.181897267195;8553.657853685845;11395.145364097643;11428.142889283296;11580.942095289554;11786.528538858436;11439.88560114405;11558.364289964049;11592.304461732323;11599.6520104397;11627.94186029073;37845.62154378449;38496.615033849586;38517.651882183985;38535;38543.42184867229;3170.7304879085304;3226.2418331692033;3251.96748032519;3827.4067519534515;4029.6977726670616;28130.296742581395;28971;29289.56065659017;29293.414131717458;29632;2732.3269295451382;2740.4519096180643;2761.4615150045747;2826.5194916863975;2840.332521857362;489063;490071;490272.5486372545;491152;491244;59543.106853397236;59716.70141649264;59944;60023.69988150031;60182;62295.45897680769;63086.73826523444;63205.683971580766;63207;63238.36761632417;13545.39045742944;16340.428085016967;16506.2572184252;16582.336706531845;16709.83290167107;11799.764004719858;12084.577039803564;12707.555235566895;12958.611241662766;13053.216806991612;15189.6202594936;15193.924030379778;15290;15351.846481535265;15445.922770386076;5338.733063346817;6172.969135154295;6336.20797400325;6394.808155755335;6427.2929977702415;955.617752898846;983.5672304186136;1021.3667526133845;1024.2369434771142;1044.8014877173314;24665.876670616533;28446.2698144354;28486.430271394456;29137.41725165485;29761.660725267415;1006.411249419098;1099.8955099265556;1135.0692432205512;1194.7371578252944;1218.165556593736;14861.851381486264;14942.25288735559;14952.626184345261;15302.158381289137;15375.769363459556;573271;717749;734151;735872;736692;55948;57100.71449642725;57202.7139864298;57379.71310143506;57388.713056435285;1324.8807607315389;1331.6271443995697;1420.7268370606946;1435.978460323096;1481.6962522682825;22042.44893877666;24928.37679058038;25522.23433297004;25755.484890302465;26041.739582603932;45087.774561128295;45340.093198136514;45900;45960.31059534177;45965.85085372892;336344;340238;371626;371933;372234.13882931496;22340.664890026987;25102.748972510042;25128.36665616729;25622.359441014123;25756.8712156438;41908.79045604752;42305.78847105745;42750.572494275904;43123.56876431195;43570.56429435665;35227.47158792672;35283.117922051126;35671;36105;36178.63821361752;34980.12549686278;35384;35669.28661426809;35817.1045723859;35898.82050589835;34983.300333992665;35342;35521;35958.101047474025;36130.45804312885;30292.091237262917;31065.37869242648;31067.68932310648;31248.375032498767;31253;21933.01301441407;22107.33677989663;22109.11563537505;22202.66695999529;22212.77787222107 18:50:35 INFO - PROCESS | 1948 | |2;jquery.html;381.41452869844795;385.29683327926534;390.14947414636083;398.3652271419509;402.7824974513763;13962.556969982714;19738;20430.591388172244;20431;20454.897725511353;15959.601009974756;17514.737278940793;20319.085641146146;20755.273565425214;20879;4406.471223453182;5551.250581171551;5926.762929482816;5961.701914904259;5980.10298455233;5522.171674248874;5901.4688678018965;5910.793122240722;5946.61347012445;5970.462658360747;3780.754250973692;4051.8784436466954;5647.322321321447;5971.044632858743;5978.342382337939;5566.276384070045;5940.61386009908;5946.464818166386;5960.433758792908;5968.343482216953;5691.630044047165;5720.456556627156;5725.1698503716725;5728.42715728428;5730.05454100073;12933.417996190194;14467.855321446756;21495.453250213697;24932.3766905827;25500.872495637588;2038.5718999010226;2119.470132466883;2136.465883529118;2158.5359147783147;2169.5877783221295;9618.663346782829;9827;9980.850287245697;10057.044580764816;10115.089641932262;1736.6266252755593;1745.0751101915878;1784.1792775323368;1804.2602532961555;1830.2221555838848;107840;108488;109852;112303.9463224157;113056.4347178267;8491.363147763943;11397.202195846212;11498.655040348804;11592.826107608393;11736.941315293368;14586.854131458762;14683.339249733788;14705.632359191106;14709.043912145677;14721.484748033981;8284.958575207085;8454.87317690235;8466.788330291793;8548.786280343042;8558.400911936229;8302.54336011513;8303.709370172015;8324.125966773523;8442;8445.155484451505;4467.6649251305635;4534;4557;4605.102005109038;4622.815087396467;1922.480930148852;1935.2742721479444;1940.6991916253003;1974.5261137327225;1998.3305592626632;724.5799031235698;749.3480671815555;755.6788364944877;790.4545863354267;844.6114787197957;18343;19081.713774293396;19591.902040489706;19704.01479925998;19934.50163745918;881.140887634564;920.8158368326227;936.8079543693663;939.4832841936908;974.9902500975041;7843.821507445598;10323.328983616191;10442.373457592568;10469.9476502617;10584.947075264574;497621;512033;538407;548569;556179;84577;120688.79311206951;122517;125570;127465;990.841465365538;991.2466525440573;1004.638330201127;1008.6570566007592;1018.8777346718293;17373.218205180543;17512.737308940632;17557.209925553638;17570.56073598119;17629.82370176282;46402.76798615985;46810;48036.27945580749;49103.754481228796;51227;67985.6600717013;70953.64523177351;71058.28941710517;72092.27907721065;74694.25305746873;7453.701851925862;7811.101723301893;7976.680932762857;8019.679212831421;8154.306883914934;8522.019967703545;9016.954915225382;11995.340256285988;12269.447874845831;12277.017838572714;10148.898511014795;10278.845817312897;10312.793744125225;10395.636152734618;10457.790844182922;9910.058544438267;10109.898901010896;10274.897251027394;10286.845697314397;10432.374057556572;8971.865422018544;10341.63804266847;10487.68845220576;10593.735156620838;10754.300970436896;7807.609619519115;7858.646360913887;7863.606819659109;7934.64294106755;8070.798230044295;9993.550290237103;10220.948895255477;10548.156147508334;10562.207834412604;10644.467776611293 18:50:35 INFO - PROCESS | 1948 | |3;mootools.html;1361.7072329449165;1597.0577359357976;1601.4555051282566;1858.507495513689;1981.4848139483738;7872.173421790708;8503.02215245247;8776.034636190014;8776.42953208041;8784.341174411913;8296.12890646481;8627.870581941275;8638.568071596426;8643.049264580906;8645.876036115296;2407.374082738492;2464.4824586836694;2467.950640987179;2471.3080337505526;2474.307193985687;2386.570417324881;2450.987745061281;2451.1421002649017;2454.5827209374447;2463.3472129885595;2413.806895448369;2443.218170185541;2453.6442215878687;2459.225344016638;2463.4210960424284;2407.037185125946;2425.9757402426017;2434.3914021494625;2437.073911913479;2437.8293519453555;2403.339081752532;2407.1214006887535;2415.5893498105365;2423.7818596326238;2433.720122185936;8407.70573029947;8537;8630.568471576396;8640.870386944202;8642.222200001966;5240.737963101829;5414.3232095988005;5427.968685949657;5465;5477.315335583052;3427.7600567960253;3535.257595904863;3600.495930569696;3647.5075864758182;3649.4160934250394;1020.8877023527406;1041.7916416716687;1044.2898828796415;1052.6736711619346;1053.4364115198332;11072.501737421835;11259.21185517014;11266.662000139928;11272.549098036068;11285.266457680225;4672.6261899047995;4782.37829082224;4788.809493553362;4926.1379258629595;4939.580135688435;9982.201423886072;10011.449370284705;10029.949850250701;10036.297459177787;10038.447885366375;5399.271098401744;5413.512783849473;5441.83674489766;5450.182472629058;5458.372287187024;4271.9359209611885;4466.419365482467;4606.055758569485;4861.538153875376;4953.628477864175;3993.980030099831;4023.939640905389;4072.470578824793;4092.1201941582704;4124.360724087771;1317.4532568983957;1347.883822398844;1350.547566565192;1360.6394305509048;1364.781634938405;831.3349320543662;900.6127365232882;920.070728564155;943.9056094390506;954.289054654287;5673.489385955284;5876.294844618587;5951.642901425928;5975.910361344583;6083.422074902877;456.52087237351407;460.6222897224311;463.88866671999153;484.5638924967487;486.64718079392156;3853.402722578005;3939.3106206414336;3973.046468847456;4036.1725846201457;4058.6144316289906;10139.391636501834;10527.789444211227;10547.578096876192;10664.466776661136;10688.572457101629;10588.841167382496;10589.046985771318;10597;10606.15150787935;10609.681709548726;7149.392301654314;7993.360531157494;8014.679412823421;8103.189681031849;8103.554304513191;4805.927911081407;5844.415558444121;5998;6005.099235114768;6065.090236464565;9469.47917864524;9663.178629816544;9758.80482390334;9791.069848364394;9841.409515429097;9693.079157480028;9892.703218903445;9930.602775888883;10092.697219083439;10228.07947284748;6629.436497897733;6884.8967265490055;7092.078029856086;7507.774766757006;7524.510906791041;8179.959100204461;8208.876866846882;8274.875876861732;8278.875816862632;8281.54451505173;7232.63836808147;7328.853422931618;7397.741079062207;7436.18201997769;7474.962625186839;7133.928660713326;7188.388986936169;7294.817629559304;7313.597752123685;7355.374793142642;6012.647154390227;6948.270431604608;6972.302769722987;6980.825479362853;7025.086738723933;4757.35775670294;4821.252705830602;4869.269609558594;4967.726775027264;5098.5411312982005;4870.522205466791;4918.360613120271;4951.603871690329;5018.5232402921665;5189.429162792015 18:50:35 INFO - PROCESS | 1948 | |4;prototype.html;311.37103051835305;316.4383219784882;321.7635038246888;329.3626832079927;329.55674617639283;5046.066477701624;5538.584606154544;5788.536917046631;5827.35899051104;5854.443827836354;4298.333758267466;5206.479352064792;5739.827805165842;5753.482186603205;5762.971185144069;4345.913081738363;4357.27887484445;4368.344748287766;4381.277089280265;4392.155294776194;3534.5228394166716;3723.6487161554546;4221.261279276115;4382.408374869384;4399.956000439986;4292.227399068166;4328.480582330125;4329.913401731964;4332;4335.154644844257;4318.9784051079705;4323.22182007237;4356.499002614708;4358.019445624741;4368.737875727466;3453.9827300863585;3525.4183059795105;3779.6031416701126;3784.791836448994;3788.109794198361;5059.494050594948;5422.647527910713;5430.321209848769;5447.4824891635235;5458.153986132156;4799.904001919942;4844.8304309349005;4846.224604063333;4858.927116093261;4904;5791.509650850133;5993.340732519463;6018.96990515049;6025.728842202111;6027.216461859974;3970.4838371011583;3987.464826041966;3993.8617494014356;4009.7594144351433;4022.1955608878343;6033.5173186145;6307.747690092391;6313.779017734403;6408.93591064088;6506.121673574102;3388.9152771180916;3656.6160553141535;4021.3163762160502;4023.4568333274633;4031.9395209071886;3489.4416893296952;3922.2743792398496;4154.958450415457;4156.916861662749;4183;4781.928271075936;4839.395075615548;4856.271559266068;4857.951420485821;4893.657443978958;5721.542276617861;5797.826065218051;6071.51427885768;6119.84700382494;6125.326214116443;1419.7373485904934;1433.8852891768634;1436.2675035731852;1437.4393986345333;1442.3004842651305;814.6659869453614;912.9132732390412;936.8266870628955;936.840737074699;948.5684013773716;5331.813386531453;5521.1442226454965;5545.251391062155;5582.91625625616;5610.887782244332;5512.476314750093;6174.104754810527;6181.887260293191;6216.844578885564;6240.500759939194;4837.105135549935;5297.761600727977;5626.2967129108865;5671.574631902624;5706.201131841545;3289.8190599517256;3316.7512436567354;3318.9170270742943;3346.748993825473;3348.263382055944;5283.815066472655;5543.223948647193;6160.322364539897;6239.845628558731;6252.718627661675;6491.67541622917;6519.902201466982;6525.4453371463005;6548.443382312462;6569.605823650596;6073.514118870568;6176.3514830943;6612.8016159516255;6620.271770105284;6655.301193374626;4720.150372932905;5998.940010599838;6022.049195894068;6140.754369825157;6337.4613157882095;6122.877542449215;6234.906476402767;6237.906431403442;6248.12526246335;6292.370762923671;5354.732263386737;6310.905336420049;6641.1034510342415;6644.966775166094;6657;3833.789141597239;3934.3705007199856;4087.2642924273914;4129.958700412957;4143.150654115839;5991.281046274475;6310.905336420049;6326.746930122744;6328.272248691275;6423.614583125028;5782.884342313214;5908.527317814479;6100.115483254992;6125.693715314305;6134.631922084689;6055.6063855849225;6060.939390606214;6118.694065296806;6119.571629985859;6122.785702500391;5219.164933610604;5561.332640083216;5978.581499295182;5986.623076692472;6015.4586087252355;5843.2403787507355;6061.272647282354;6073.171828396524;6077.51379889599;6115.3578874217565;5925.022371308686;5936.1095835625;5947.6431414115295;5968.850778730566;6023.337432882292 18:50:35 INFO - PROCESS | 1948 | |5;yui.html;730.9086364204475;732.5677850068458;736.1644533454528;737.8450525389668;749.995006691034;14784.334704938277;15272;15285;15296.235188240586;15327.386904523819;13952.651183720413;14048.71902561948;14049;14051.578452646412;14068.648283792885;718.0880282041804;872.2150064941571;906.9932375063707;912.8493798523235;914.5838643417262;903.6882275614902;913.0641092879819;914.6570036236411;915.4370062411635;919.9862002069941;968.9563969621383;977.8484334928062;980.7646164920403;980.8430651095827;981.0679854138597;751.5077624156187;783.7139444102918;960.9759756006085;968.2350942755215;973.1971123822804;716.6703316474428;723.5477826358526;725.521156037013;728.3990707666144;729.8832186850078;14719.705605887822;14762.70474590513;14780.778288325684;14791.40834366624;14792.630184245372;12344.81482777759;12561.101170543496;12649.304288264142;12887.097903146783;12998.740025199537;9927.305088643798;9975.501224938724;10118;10148.94925525375;10559.313644613076;3508.596511401196;3513.5256740339983;3519.3665140274998;3601.9099522511883;3604.008897553194;74453.62773186153;75167.62416187939;75335.24664753392;78640;79229.60385198037;9566.760830979141;9694.951525242328;9701.07839755222;9719.319647624598;9939.403635781877;20325.695114573293;22340;22341.329760107223;22346.329610111723;22387.104515819512;7459.888101678479;7556.848863022708;7598.088229412506;7614.2766437188375;7633.6564854581675;4052.2908491013955;4150.771707556113;4150.896227594334;4157.584241575818;4163.521195062546;3475.3396854597545;3572.4819901114192;3574.4995700601935;3575.3028159508644;3575.481555174485;632.5666918161074;648.3743187823678;652.1131261484378;653.6274323635578;657.6678777217475;422.5837550013234;570.8601392658742;574.9507149452267;581.4679568195054;588.9322727886263;7836.882446763303;8009.559474228857;8024.7191348303695;8029.116797152308;8107.351411887153;565.3780841074785;579.7188363643629;586.2613107484515;594.8869714754182;604.4137186928672;3577.159367548624;3676.761010534307;3785.413260944558;3796.335641262823;3802.3535998880257;80270;81785.59107204546;81852.18147818565;82074.58962705148;82373.58813205895;61669.691651541456;62205.06692399618;62395;63290.68354658289;63461.68269158625;62074.37925620686;63445.41386465283;64496.355036449975;65213.67393163004;65775.99999999904;6619.404253617197;7298.4526160539235;7402.629868506661;7475.962620186864;7833.843323133391;40545.59454405536;41447.58552414558;41978.37032444455;42111.578884210765;42461;40077.398839018024;56672;58081.70959145177;58130.83738325295;58249;8181.468204566684;9784.266180036668;10127.189824814144;10442.269041167045;10611.204159688208;11478.139139564399;11530.884691152982;11570.826437603611;11681.240719353214;11989.520419183134;11245.325280483197;11364.772704545698;11510.194286399872;11527;11758.647240582795;11533.135014874086;11586.826197607212;11808.881911180779;12128.332941687939;12472.87527124717;11268.211225214156;11302.152338574804;11376.431178441211;11537.868884238585;11617.6514704559;9525.571349289437;9526.761830954281;9547.084553194203;9750.024997500477;9937.602495900082;9464.337496375463;9817.361871478332;9857.11285984265;9919.40483570988;10002.699919002442 18:50:35 INFO - PROCESS | 1948 | __end_tp_report 18:50:35 INFO - PROCESS | 1948 | __start_cc_report 18:50:35 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1162 18:50:35 INFO - PROCESS | 1948 | __end_cc_report 18:50:35 INFO - PROCESS | 1948 | __startTimestamp1460598635620__endTimestamp 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | ------- Summary: start ------- 18:50:35 INFO - PROCESS | 1948 | Number of tests: 6 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5631.04 Median:5805.88 stddev:639.96 (11.0%) stddev-sans-first:602.41 18:50:35 INFO - PROCESS | 1948 | Values: 2808.7 3397.2 3417.8 3439.3 3481.0 5490.8 5531.4 5543.3 5576.9 5615.2 5572.9 5585.7 5635.4 5742.7 5747.3 5069.2 5100.0 5124.1 5134.3 5224.5 5168.5 5207.7 5222.5 5222.8 5239.7 4662.3 5068.7 5158.6 5181.4 5210.9 5076.8 5083.8 5108.9 5145.3 5146.0 5066.5 5093.7 5118.6 5125.1 5137.6 5431.7 5509.0 5527.0 5529.2 5604.6 6105.8 6105.8 6148.8 6160.0 6161.7 5642.4 6199.5 6219.4 6233.6 6234.5 5542.8 5615.6 5650.5 5674.9 5716.6 6280.9 6286.9 6296.2 6298.4 6309.5 5982.9 5986.3 5995.9 6007.3 6011.6 6035.8 6065.6 6080.6 6108.7 6114.6 6093.9 6101.3 6111.8 6114.5 6125.9 6115.1 6126.7 6141.1 6141.5 6146.8 4741.8 4789.4 4796.4 4868.0 4871.4 4315.2 4316.0 4356.8 4392.7 4439.6 5507.3 5540.6 5543.3 5554.1 5559.4 4950.0 6124.5 6127.1 6199.6 6201.7 5495.8 5529.0 5529.6 5584.2 5600.4 5254.9 5256.1 5272.8 5281.5 5295.3 6175.4 6176.8 6217.0 6298.1 6306.4 6161.7 6203.4 6215.1 6221.4 6227.2 6272.0 6285.6 6289.1 6295.6 6305.3 5923.7 6027.8 6032.2 6052.1 6095.6 6129.4 6190.2 6198.1 6198.9 6218.4 5939.3 5977.5 5983.5 5992.1 6034.9 5789.8 5857.7 5891.5 5919.4 5972.3 5014.8 5805.9 5815.5 5834.7 5860.7 5945.0 5971.9 5995.7 6019.2 6049.7 5575.9 5663.9 5782.2 5811.7 5830.0 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:64656.87 Median:184834.42 stddev:143858.33 (77.8%) stddev-sans-first:144181.26 18:50:35 INFO - PROCESS | 1948 | Values: 506.2 527.2 574.2 610.6 644.6 29900.7 33324.8 37346.8 38252.8 38691.0 39929.4 40276.0 40392.6 40443.0 40452.0 8477.0 9444.0 9666.7 9670.6 9693.1 8617.2 11495.8 11867.5 11870.5 11879.0 11540.5 11667.0 11668.0 11686.3 11687.2 8553.7 11395.1 11428.1 11580.9 11786.5 11439.9 11558.4 11592.3 11599.7 11627.9 37845.6 38496.6 38517.7 38535.0 38543.4 3170.7 3226.2 3252.0 3827.4 4029.7 28130.3 28971.0 29289.6 29293.4 29632.0 2732.3 2740.5 2761.5 2826.5 2840.3 489063.0 490071.0 490272.5 491152.0 491244.0 59543.1 59716.7 59944.0 60023.7 60182.0 62295.5 63086.7 63205.7 63207.0 63238.4 13545.4 16340.4 16506.3 16582.3 16709.8 11799.8 12084.6 12707.6 12958.6 13053.2 15189.6 15193.9 15290.0 15351.8 15445.9 5338.7 6173.0 6336.2 6394.8 6427.3 955.6 983.6 1021.4 1024.2 1044.8 24665.9 28446.3 28486.4 29137.4 29761.7 1006.4 1099.9 1135.1 1194.7 1218.2 14861.9 14942.3 14952.6 15302.2 15375.8 573271.0 717749.0 734151.0 735872.0 736692.0 55948.0 57100.7 57202.7 57379.7 57388.7 1324.9 1331.6 1420.7 1436.0 1481.7 22042.4 24928.4 25522.2 25755.5 26041.7 45087.8 45340.1 45900.0 45960.3 45965.9 336344.0 340238.0 371626.0 371933.0 372234.1 22340.7 25102.7 25128.4 25622.4 25756.9 41908.8 42305.8 42750.6 43123.6 43570.6 35227.5 35283.1 35671.0 36105.0 36178.6 34980.1 35384.0 35669.3 35817.1 35898.8 34983.3 35342.0 35521.0 35958.1 36130.5 30292.1 31065.4 31067.7 31248.4 31253.0 21933.0 22107.3 22109.1 22202.7 22212.8 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:31755.20 Median:394.26 stddev:88819.28 (22528.2%) stddev-sans-first:89037.20 18:50:35 INFO - PROCESS | 1948 | Values: 381.4 385.3 390.1 398.4 402.8 13962.6 19738.0 20430.6 20431.0 20454.9 15959.6 17514.7 20319.1 20755.3 20879.0 4406.5 5551.3 5926.8 5961.7 5980.1 5522.2 5901.5 5910.8 5946.6 5970.5 3780.8 4051.9 5647.3 5971.0 5978.3 5566.3 5940.6 5946.5 5960.4 5968.3 5691.6 5720.5 5725.2 5728.4 5730.1 12933.4 14467.9 21495.5 24932.4 25500.9 2038.6 2119.5 2136.5 2158.5 2169.6 9618.7 9827.0 9980.9 10057.0 10115.1 1736.6 1745.1 1784.2 1804.3 1830.2 107840.0 108488.0 109852.0 112303.9 113056.4 8491.4 11397.2 11498.7 11592.8 11736.9 14586.9 14683.3 14705.6 14709.0 14721.5 8285.0 8454.9 8466.8 8548.8 8558.4 8302.5 8303.7 8324.1 8442.0 8445.2 4467.7 4534.0 4557.0 4605.1 4622.8 1922.5 1935.3 1940.7 1974.5 1998.3 724.6 749.3 755.7 790.5 844.6 18343.0 19081.7 19591.9 19704.0 19934.5 881.1 920.8 936.8 939.5 975.0 7843.8 10323.3 10442.4 10469.9 10584.9 497621.0 512033.0 538407.0 548569.0 556179.0 84577.0 120688.8 122517.0 125570.0 127465.0 990.8 991.2 1004.6 1008.7 1018.9 17373.2 17512.7 17557.2 17570.6 17629.8 46402.8 46810.0 48036.3 49103.8 51227.0 67985.7 70953.6 71058.3 72092.3 74694.3 7453.7 7811.1 7976.7 8019.7 8154.3 8522.0 9017.0 11995.3 12269.4 12277.0 10148.9 10278.8 10312.8 10395.6 10457.8 9910.1 10109.9 10274.9 10286.8 10432.4 8971.9 10341.6 10487.7 10593.7 10754.3 7807.6 7858.6 7863.6 7934.6 8070.8 9993.6 10220.9 10548.2 10562.2 10644.5 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5651.97 Median:4865.40 stddev:3125.04 (64.2%) stddev-sans-first:3117.17 18:50:35 INFO - PROCESS | 1948 | Values: 1361.7 1597.1 1601.5 1858.5 1981.5 7872.2 8503.0 8776.0 8776.4 8784.3 8296.1 8627.9 8638.6 8643.0 8645.9 2407.4 2464.5 2468.0 2471.3 2474.3 2386.6 2451.0 2451.1 2454.6 2463.3 2413.8 2443.2 2453.6 2459.2 2463.4 2407.0 2426.0 2434.4 2437.1 2437.8 2403.3 2407.1 2415.6 2423.8 2433.7 8407.7 8537.0 8630.6 8640.9 8642.2 5240.7 5414.3 5428.0 5465.0 5477.3 3427.8 3535.3 3600.5 3647.5 3649.4 1020.9 1041.8 1044.3 1052.7 1053.4 11072.5 11259.2 11266.7 11272.5 11285.3 4672.6 4782.4 4788.8 4926.1 4939.6 9982.2 10011.4 10029.9 10036.3 10038.4 5399.3 5413.5 5441.8 5450.2 5458.4 4271.9 4466.4 4606.1 4861.5 4953.6 3994.0 4023.9 4072.5 4092.1 4124.4 1317.5 1347.9 1350.5 1360.6 1364.8 831.3 900.6 920.1 943.9 954.3 5673.5 5876.3 5951.6 5975.9 6083.4 456.5 460.6 463.9 484.6 486.6 3853.4 3939.3 3973.0 4036.2 4058.6 10139.4 10527.8 10547.6 10664.5 10688.6 10588.8 10589.0 10597.0 10606.2 10609.7 7149.4 7993.4 8014.7 8103.2 8103.6 4805.9 5844.4 5998.0 6005.1 6065.1 9469.5 9663.2 9758.8 9791.1 9841.4 9693.1 9892.7 9930.6 10092.7 10228.1 6629.4 6884.9 7092.1 7507.8 7524.5 8180.0 8208.9 8274.9 8278.9 8281.5 7232.6 7328.9 7397.7 7436.2 7475.0 7133.9 7188.4 7294.8 7313.6 7355.4 6012.6 6948.3 6972.3 6980.8 7025.1 4757.4 4821.3 4869.3 4967.7 5098.5 4870.5 4918.4 4951.6 5018.5 5189.4 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4923.27 Median:5572.12 stddev:1546.80 (27.8%) stddev-sans-first:1511.91 18:50:35 INFO - PROCESS | 1948 | Values: 311.4 316.4 321.8 329.4 329.6 5046.1 5538.6 5788.5 5827.4 5854.4 4298.3 5206.5 5739.8 5753.5 5763.0 4345.9 4357.3 4368.3 4381.3 4392.2 3534.5 3723.6 4221.3 4382.4 4400.0 4292.2 4328.5 4329.9 4332.0 4335.2 4319.0 4323.2 4356.5 4358.0 4368.7 3454.0 3525.4 3779.6 3784.8 3788.1 5059.5 5422.6 5430.3 5447.5 5458.2 4799.9 4844.8 4846.2 4858.9 4904.0 5791.5 5993.3 6019.0 6025.7 6027.2 3970.5 3987.5 3993.9 4009.8 4022.2 6033.5 6307.7 6313.8 6408.9 6506.1 3388.9 3656.6 4021.3 4023.5 4031.9 3489.4 3922.3 4155.0 4156.9 4183.0 4781.9 4839.4 4856.3 4858.0 4893.7 5721.5 5797.8 6071.5 6119.8 6125.3 1419.7 1433.9 1436.3 1437.4 1442.3 814.7 912.9 936.8 936.8 948.6 5331.8 5521.1 5545.3 5582.9 5610.9 5512.5 6174.1 6181.9 6216.8 6240.5 4837.1 5297.8 5626.3 5671.6 5706.2 3289.8 3316.8 3318.9 3346.7 3348.3 5283.8 5543.2 6160.3 6239.8 6252.7 6491.7 6519.9 6525.4 6548.4 6569.6 6073.5 6176.4 6612.8 6620.3 6655.3 4720.2 5998.9 6022.0 6140.8 6337.5 6122.9 6234.9 6237.9 6248.1 6292.4 5354.7 6310.9 6641.1 6645.0 6657.0 3833.8 3934.4 4087.3 4130.0 4143.2 5991.3 6310.9 6326.7 6328.3 6423.6 5782.9 5908.5 6100.1 6125.7 6134.6 6055.6 6060.9 6118.7 6119.6 6122.8 5219.2 5561.3 5978.6 5986.6 6015.5 5843.2 6061.3 6073.2 6077.5 6115.4 5925.0 5936.1 5947.6 5968.9 6023.3 18:50:35 INFO - PROCESS | 1948 | 18:50:35 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:16666.90 Median:599.65 stddev:22337.81 (3725.1%) stddev-sans-first:22368.42 18:50:35 INFO - PROCESS | 1948 | Values: 730.9 732.6 736.2 737.8 750.0 14784.3 15272.0 15285.0 15296.2 15327.4 13952.7 14048.7 14049.0 14051.6 14068.6 718.1 872.2 907.0 912.8 914.6 903.7 913.1 914.7 915.4 920.0 969.0 977.8 980.8 980.8 981.1 751.5 783.7 961.0 968.2 973.2 716.7 723.5 725.5 728.4 729.9 14719.7 14762.7 14780.8 14791.4 14792.6 12344.8 12561.1 12649.3 12887.1 12998.7 9927.3 9975.5 10118.0 10148.9 10559.3 3508.6 3513.5 3519.4 3601.9 3604.0 74453.6 75167.6 75335.2 78640.0 79229.6 9566.8 9695.0 9701.1 9719.3 9939.4 20325.7 22340.0 22341.3 22346.3 22387.1 7459.9 7556.8 7598.1 7614.3 7633.7 4052.3 4150.8 4150.9 4157.6 4163.5 3475.3 3572.5 3574.5 3575.3 3575.5 632.6 648.4 652.1 653.6 657.7 422.6 570.9 575.0 581.5 588.9 7836.9 8009.6 8024.7 8029.1 8107.4 565.4 579.7 586.3 594.9 604.4 3577.2 3676.8 3785.4 3796.3 3802.4 80270.0 81785.6 81852.2 82074.6 82373.6 61669.7 62205.1 62395.0 63290.7 63461.7 62074.4 63445.4 64496.4 65213.7 65776.0 6619.4 7298.5 7402.6 7476.0 7833.8 40545.6 41447.6 41978.4 42111.6 42461.0 40077.4 56672.0 58081.7 58130.8 58249.0 8181.5 9784.3 10127.2 10442.3 10611.2 11478.1 11530.9 11570.8 11681.2 11989.5 11245.3 11364.8 11510.2 11527.0 11758.6 11533.1 11586.8 11808.9 12128.3 12472.9 11268.2 11302.2 11376.4 11537.9 11617.7 9525.6 9526.8 9547.1 9750.0 9937.6 9464.3 9817.4 9857.1 9919.4 10002.7 18:50:35 INFO - PROCESS | 1948 | -------- Summary: end -------- 18:50:35 INFO - PROCESS | 1948 | 18:50:36 INFO - TEST-INFO | 1948: exit 0 18:50:36 INFO - TEST-OK | dromaeo_css | took 1113906ms 18:50:36 INFO - TEST-START | kraken 18:50:36 INFO - operating with platform_type : mac_ 18:50:36 INFO - Initialising browser for kraken test... 18:50:36 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpqiuskF/profile http://localhost:51823/getInfo.html) 18:50:38 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 18:50:38 INFO - PROCESS | 1951 | colorDepth:24 18:50:38 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 18:50:38 INFO - PROCESS | 1951 | __metrics 18:50:38 INFO - PROCESS | 1951 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:50:38 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:50:39 INFO - TEST-INFO | 1951: exit 0 18:50:39 INFO - Browser initialized. 18:50:39 INFO - Running cycle 1/1 for kraken test... 18:50:39 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpqiuskF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:50 INFO - PROCESS | 1953 | RSS: Main: 237608960 18:50:50 INFO - PROCESS | 1953 | 18:50:53 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51823/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:54 INFO - PROCESS | 1953 | RSS: Main: 285814784 18:50:54 INFO - PROCESS | 1953 | 18:50:57 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51823/tests/kraken/driver.html?testName=audio-dft) 18:50:57 INFO - PROCESS | 1953 | RSS: Main: 299479040 18:50:57 INFO - PROCESS | 1953 | 18:51:01 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51823/tests/kraken/driver.html?testName=audio-fft) 18:51:01 INFO - PROCESS | 1953 | RSS: Main: 385859584 18:51:01 INFO - PROCESS | 1953 | 18:51:03 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51823/tests/kraken/driver.html?testName=audio-oscillator) 18:51:04 INFO - PROCESS | 1953 | RSS: Main: 286326784 18:51:04 INFO - PROCESS | 1953 | 18:51:06 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51823/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:07 INFO - PROCESS | 1953 | RSS: Main: 291631104 18:51:07 INFO - PROCESS | 1953 | 18:51:10 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51823/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:10 INFO - PROCESS | 1953 | RSS: Main: 330199040 18:51:10 INFO - PROCESS | 1953 | 18:51:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51823/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:14 INFO - PROCESS | 1953 | RSS: Main: 329195520 18:51:14 INFO - PROCESS | 1953 | 18:51:17 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51823/tests/kraken/driver.html?testName=json-parse-financial) 18:51:17 INFO - PROCESS | 1953 | RSS: Main: 330383360 18:51:17 INFO - PROCESS | 1953 | 18:51:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51823/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:19 INFO - PROCESS | 1953 | RSS: Main: 319762432 18:51:19 INFO - PROCESS | 1953 | 18:51:21 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:22 INFO - PROCESS | 1953 | RSS: Main: 327135232 18:51:22 INFO - PROCESS | 1953 | 18:51:24 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:25 INFO - PROCESS | 1953 | RSS: Main: 329449472 18:51:25 INFO - PROCESS | 1953 | 18:51:28 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:28 INFO - PROCESS | 1953 | RSS: Main: 334352384 18:51:28 INFO - PROCESS | 1953 | 18:51:31 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:32 INFO - PROCESS | 1953 | RSS: Main: 331968512 18:51:32 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51823/tests/kraken/driver.html?testName=ai-astar) 18:51:34 INFO - PROCESS | 1953 | RSS: Main: 395694080 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | __start_tp_report 18:51:34 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 18:51:34 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 18:51:34 INFO - PROCESS | 1953 | |i|pagename|runs| 18:51:34 INFO - PROCESS | 1953 | |0;ai-astar;113;109;113;113;113;112;118;113;113;113 18:51:34 INFO - PROCESS | 1953 | |1;audio-beat-detection;158;121;143;170;119;143;142;119;172;120 18:51:34 INFO - PROCESS | 1953 | |2;audio-dft;187;186;187;184;186;187;256;235;184;189 18:51:34 INFO - PROCESS | 1953 | |3;audio-fft;71;77;86;89;55;59;63;78;85;54 18:51:34 INFO - PROCESS | 1953 | |4;audio-oscillator;92;95;134;77;79;80;125;79;76;121 18:51:34 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;88;84;88;84;89;131;89;85;88;85 18:51:34 INFO - PROCESS | 1953 | |6;imaging-darkroom;93;99;151;91;130;149;93;93;92;93 18:51:34 INFO - PROCESS | 1953 | |7;imaging-desaturate;87;98;121;86;85;84;89;84;85;86 18:51:34 INFO - PROCESS | 1953 | |8;json-parse-financial;56;58;57;58;58;58;58;72;59;56 18:51:34 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;65;64;76;80;72;72;68;69;69;75 18:51:34 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;85;85;85;84;85;84;84;85;85;87 18:51:34 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;145;143;142;142;138;140;143;145;181;142 18:51:34 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;169;169;171;171;182;206;225;223;175;167 18:51:34 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;68;66;69;69;69;68;69;68;64;67 18:51:34 INFO - PROCESS | 1953 | __end_tp_report 18:51:34 INFO - PROCESS | 1953 | __start_cc_report 18:51:34 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1964 18:51:34 INFO - PROCESS | 1953 | __end_cc_report 18:51:34 INFO - PROCESS | 1953 | __startTimestamp1460598694472__endTimestamp 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | ------- Summary: start ------- 18:51:34 INFO - PROCESS | 1953 | Number of tests: 14 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:2.16 (1.9%) stddev-sans-first:2.29 18:51:34 INFO - PROCESS | 1953 | Values: 113.0 109.0 113.0 113.0 113.0 112.0 118.0 113.0 113.0 113.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:143.00 stddev:20.84 (14.6%) stddev-sans-first:21.14 18:51:34 INFO - PROCESS | 1953 | Values: 158.0 121.0 143.0 170.0 119.0 143.0 142.0 119.0 172.0 120.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:198.10 Median:187.00 stddev:25.51 (13.6%) stddev-sans-first:26.74 18:51:34 INFO - PROCESS | 1953 | Values: 187.0 186.0 187.0 184.0 186.0 187.0 256.0 235.0 184.0 189.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:71.70 Median:77.50 stddev:13.24 (17.1%) stddev-sans-first:14.04 18:51:34 INFO - PROCESS | 1953 | Values: 71.0 77.0 86.0 89.0 55.0 59.0 63.0 78.0 85.0 54.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.00 stddev:22.41 (28.4%) stddev-sans-first:23.73 18:51:34 INFO - PROCESS | 1953 | Values: 92.0 95.0 134.0 77.0 79.0 80.0 125.0 79.0 76.0 121.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:88.00 stddev:14.16 (16.1%) stddev-sans-first:14.98 18:51:34 INFO - PROCESS | 1953 | Values: 88.0 84.0 88.0 84.0 89.0 131.0 89.0 85.0 88.0 85.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:93.00 stddev:24.81 (26.7%) stddev-sans-first:25.68 18:51:34 INFO - PROCESS | 1953 | Values: 93.0 99.0 151.0 91.0 130.0 149.0 93.0 93.0 92.0 93.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:86.00 stddev:11.48 (13.4%) stddev-sans-first:12.11 18:51:34 INFO - PROCESS | 1953 | Values: 87.0 98.0 121.0 86.0 85.0 84.0 89.0 84.0 85.0 86.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:4.67 (8.0%) stddev-sans-first:4.82 18:51:34 INFO - PROCESS | 1953 | Values: 56.0 58.0 57.0 58.0 58.0 58.0 58.0 72.0 59.0 56.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.00 stddev:5.01 (7.0%) stddev-sans-first:4.82 18:51:34 INFO - PROCESS | 1953 | Values: 65.0 64.0 76.0 80.0 72.0 72.0 68.0 69.0 69.0 75.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 18:51:34 INFO - PROCESS | 1953 | Values: 85.0 85.0 85.0 84.0 85.0 84.0 84.0 85.0 85.0 87.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:143.00 stddev:12.44 (8.7%) stddev-sans-first:13.19 18:51:34 INFO - PROCESS | 1953 | Values: 145.0 143.0 142.0 142.0 138.0 140.0 143.0 145.0 181.0 142.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.80 Median:178.50 stddev:23.13 (13.0%) stddev-sans-first:23.72 18:51:34 INFO - PROCESS | 1953 | Values: 169.0 169.0 171.0 171.0 182.0 206.0 225.0 223.0 175.0 167.0 18:51:34 INFO - PROCESS | 1953 | 18:51:34 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.50 stddev:1.64 (2.4%) stddev-sans-first:1.73 18:51:34 INFO - PROCESS | 1953 | Values: 68.0 66.0 69.0 69.0 69.0 68.0 69.0 68.0 64.0 67.0 18:51:34 INFO - PROCESS | 1953 | -------- Summary: end -------- 18:51:34 INFO - PROCESS | 1953 | 18:51:35 INFO - TEST-INFO | 1953: exit 0 18:51:35 INFO - TEST-OK | kraken | took 59098ms 18:51:35 INFO - SUITE-END | took 1173s 18:51:35 INFO - Completed test suite (00:19:33) 18:51:35 INFO - javascript benchmark 18:51:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2808.7191280871907, 3397.201657610461, 3417.794932304062, 3439.29494453637, 3481.0, 5490.835274941748, 5531.446855314466, 5543.279373681421, 5576.86057848554, 5615.157726341051, 5572.916406253909, 5585.720713964296, 5635.408282130379, 5742.712864356776, 5747.281589801275, 5069.214271787879, 5099.980003999196, 5124.103281925667, 5134.2812006319145, 5224.451432599561, 5168.508991645787, 5207.739613019353, 5222.529972302492, 5222.791088356461, 5239.738013099349, 4662.323963025359, 5068.6705364151585, 5158.613104017176, 5181.35233095863, 5210.947890521084, 5076.771545280471, 5083.847484575432, 5108.948910510884, 5145.279660847485, 5146.022255771391, 5066.468020857794, 5093.694378337274, 5118.564921981636, 5125.051865404875, 5137.563307118862, 5431.728413579305, 5509.00837849187, 5527.0, 5529.170624406331, 5604.551635869121, 6105.755769769204, 6105.786297479567, 6148.78479253224, 6159.969200153971, 6161.661108639023, 5642.362832384869, 6199.473044791198, 6219.40915613016, 6233.594816336924, 6234.532410069218, 5542.806001789918, 5615.578831587647, 5650.5479561634975, 5674.914876276859, 5716.628419152742, 6280.905786413207, 6286.874262514815, 6296.181496405529, 6298.433141017331, 6309.526785491106, 5982.910256346158, 5986.311574169026, 5995.910061349084, 6007.279126504847, 6011.639301641915, 6035.788747393908, 6065.60573562717, 6080.6047606906295, 6108.6759897029115, 6114.631938157314, 6093.878122437526, 6101.3079194897555, 6111.755529778848, 6114.540688159484, 6125.877482450415, 6115.052166914135, 6126.693665316717, 6141.109539116803, 6141.508679305645, 6146.78486252979, 4741.786619602126, 4789.401324834395, 4796.400449943757, 4868.0, 4871.415430148334, 4315.244832154361, 4315.956840431619, 4356.847510337123, 4392.670549708785, 4439.622632076245, 5507.284053073075, 5540.556755459635, 5543.334799824047, 5554.055810512223, 5559.444055594408, 4949.975250123726, 6124.479419249414, 6127.128444391265, 6199.597026193283, 6201.658908759971, 5495.752691128909, 5529.0, 5529.585281103933, 5584.162375643686, 5600.383957764724, 5254.9211761822835, 5256.106461901486, 5272.815451459181, 5281.471852814765, 5295.285136506599, 6175.413335733098, 6176.845578860564, 6216.968915155395, 6298.08677741734, 6306.369363063657, 6161.722722477589, 6203.379662033761, 6215.098810672517, 6221.440070393686, 6227.159333490102, 6271.96864015677, 6285.5600107992, 6289.056641503811, 6295.5907865988565, 6305.274893387319, 5923.703814809156, 6027.758889644365, 6032.215811944419, 6052.122442245762, 6095.603785753912, 6129.387061294014, 6190.164327815687, 6198.0702894566175, 6198.876022479615, 6218.409251121137, 5939.316978547349, 5977.521798256216, 5983.461488466059, 5992.0712289595185, 6034.8793024138395, 5789.797357092481, 5857.677827719346, 5891.469767720926, 5919.437653422918, 5972.343042265435, 5014.84955451337, 5805.883882322414, 5815.476607105381, 5834.7082645866685, 5860.677662728591, 5944.970275148597, 5971.940280597139, 5995.670238136944, 6019.157317975574, 6049.727762250797, 5575.888482230252, 5663.943360566342, 5782.248307720138, 5811.738471768866, 5830.0], "value": 5590.053401307781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.17998841876164, 527.1539179616715, 574.1559906936802, 610.5848023344126, 644.5552568727578, 29900.700992990063, 33324.83363082287, 37346.81326593367, 38252.80873595635, 38691.032724181874, 39929.40105898414, 40276.0, 40392.59607403925, 40443.0, 40451.988700282505, 8476.957615211915, 9443.961164271925, 9666.709998700015, 9670.613175473009, 9693.079157480028, 8617.224449799516, 11495.770084598304, 11867.465964031639, 11870.465829037714, 11879.0, 11540.480678369451, 11667.0, 11668.0, 11686.298822070661, 11687.181897267195, 8553.657853685845, 11395.145364097643, 11428.142889283296, 11580.942095289554, 11786.528538858436, 11439.88560114405, 11558.364289964049, 11592.304461732323, 11599.6520104397, 11627.94186029073, 37845.62154378449, 38496.615033849586, 38517.651882183985, 38535.0, 38543.42184867229, 3170.7304879085304, 3226.2418331692033, 3251.96748032519, 3827.4067519534515, 4029.6977726670616, 28130.296742581395, 28971.0, 29289.56065659017, 29293.414131717458, 29632.0, 2732.3269295451382, 2740.4519096180643, 2761.4615150045747, 2826.5194916863975, 2840.332521857362, 489063.0, 490071.0, 490272.5486372545, 491152.0, 491244.0, 59543.106853397236, 59716.70141649264, 59944.0, 60023.69988150031, 60182.0, 62295.45897680769, 63086.73826523444, 63205.683971580766, 63207.0, 63238.36761632417, 13545.39045742944, 16340.428085016967, 16506.2572184252, 16582.336706531845, 16709.83290167107, 11799.764004719858, 12084.577039803564, 12707.555235566895, 12958.611241662766, 13053.216806991612, 15189.6202594936, 15193.924030379778, 15290.0, 15351.846481535265, 15445.922770386076, 5338.733063346817, 6172.969135154295, 6336.20797400325, 6394.808155755335, 6427.2929977702415, 955.617752898846, 983.5672304186136, 1021.3667526133845, 1024.2369434771142, 1044.8014877173314, 24665.876670616533, 28446.2698144354, 28486.430271394456, 29137.41725165485, 29761.660725267415, 1006.411249419098, 1099.8955099265556, 1135.0692432205512, 1194.7371578252944, 1218.165556593736, 14861.851381486264, 14942.25288735559, 14952.626184345261, 15302.158381289137, 15375.769363459556, 573271.0, 717749.0, 734151.0, 735872.0, 736692.0, 55948.0, 57100.71449642725, 57202.7139864298, 57379.71310143506, 57388.713056435285, 1324.8807607315389, 1331.6271443995697, 1420.7268370606946, 1435.978460323096, 1481.6962522682825, 22042.44893877666, 24928.37679058038, 25522.23433297004, 25755.484890302465, 26041.739582603932, 45087.774561128295, 45340.093198136514, 45900.0, 45960.31059534177, 45965.85085372892, 336344.0, 340238.0, 371626.0, 371933.0, 372234.13882931496, 22340.664890026987, 25102.748972510042, 25128.36665616729, 25622.359441014123, 25756.8712156438, 41908.79045604752, 42305.78847105745, 42750.572494275904, 43123.56876431195, 43570.56429435665, 35227.47158792672, 35283.117922051126, 35671.0, 36105.0, 36178.63821361752, 34980.12549686278, 35384.0, 35669.28661426809, 35817.1045723859, 35898.82050589835, 34983.300333992665, 35342.0, 35521.0, 35958.101047474025, 36130.45804312885, 30292.091237262917, 31065.37869242648, 31067.68932310648, 31248.375032498767, 31253.0, 21933.01301441407, 22107.33677989663, 22109.11563537505, 22202.66695999529, 22212.77787222107], "value": 19243.157538155254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.41452869844795, 385.29683327926534, 390.14947414636083, 398.3652271419509, 402.7824974513763, 13962.556969982714, 19738.0, 20430.591388172244, 20431.0, 20454.897725511353, 15959.601009974756, 17514.737278940793, 20319.085641146146, 20755.273565425214, 20879.0, 4406.471223453182, 5551.250581171551, 5926.762929482816, 5961.701914904259, 5980.10298455233, 5522.171674248874, 5901.4688678018965, 5910.793122240722, 5946.61347012445, 5970.462658360747, 3780.754250973692, 4051.8784436466954, 5647.322321321447, 5971.044632858743, 5978.342382337939, 5566.276384070045, 5940.61386009908, 5946.464818166386, 5960.433758792908, 5968.343482216953, 5691.630044047165, 5720.456556627156, 5725.1698503716725, 5728.42715728428, 5730.05454100073, 12933.417996190194, 14467.855321446756, 21495.453250213697, 24932.3766905827, 25500.872495637588, 2038.5718999010226, 2119.470132466883, 2136.465883529118, 2158.5359147783147, 2169.5877783221295, 9618.663346782829, 9827.0, 9980.850287245697, 10057.044580764816, 10115.089641932262, 1736.6266252755593, 1745.0751101915878, 1784.1792775323368, 1804.2602532961555, 1830.2221555838848, 107840.0, 108488.0, 109852.0, 112303.9463224157, 113056.4347178267, 8491.363147763943, 11397.202195846212, 11498.655040348804, 11592.826107608393, 11736.941315293368, 14586.854131458762, 14683.339249733788, 14705.632359191106, 14709.043912145677, 14721.484748033981, 8284.958575207085, 8454.87317690235, 8466.788330291793, 8548.786280343042, 8558.400911936229, 8302.54336011513, 8303.709370172015, 8324.125966773523, 8442.0, 8445.155484451505, 4467.6649251305635, 4534.0, 4557.0, 4605.102005109038, 4622.815087396467, 1922.480930148852, 1935.2742721479444, 1940.6991916253003, 1974.5261137327225, 1998.3305592626632, 724.5799031235698, 749.3480671815555, 755.6788364944877, 790.4545863354267, 844.6114787197957, 18343.0, 19081.713774293396, 19591.902040489706, 19704.01479925998, 19934.50163745918, 881.140887634564, 920.8158368326227, 936.8079543693663, 939.4832841936908, 974.9902500975041, 7843.821507445598, 10323.328983616191, 10442.373457592568, 10469.9476502617, 10584.947075264574, 497621.0, 512033.0, 538407.0, 548569.0, 556179.0, 84577.0, 120688.79311206951, 122517.0, 125570.0, 127465.0, 990.841465365538, 991.2466525440573, 1004.638330201127, 1008.6570566007592, 1018.8777346718293, 17373.218205180543, 17512.737308940632, 17557.209925553638, 17570.56073598119, 17629.82370176282, 46402.76798615985, 46810.0, 48036.27945580749, 49103.754481228796, 51227.0, 67985.6600717013, 70953.64523177351, 71058.28941710517, 72092.27907721065, 74694.25305746873, 7453.701851925862, 7811.101723301893, 7976.680932762857, 8019.679212831421, 8154.306883914934, 8522.019967703545, 9016.954915225382, 11995.340256285988, 12269.447874845831, 12277.017838572714, 10148.898511014795, 10278.845817312897, 10312.793744125225, 10395.636152734618, 10457.790844182922, 9910.058544438267, 10109.898901010896, 10274.897251027394, 10286.845697314397, 10432.374057556572, 8971.865422018544, 10341.63804266847, 10487.68845220576, 10593.735156620838, 10754.300970436896, 7807.609619519115, 7858.646360913887, 7863.606819659109, 7934.64294106755, 8070.798230044295, 9993.550290237103, 10220.948895255477, 10548.156147508334, 10562.207834412604, 10644.467776611293], "value": 9033.804592247372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.7072329449165, 1597.0577359357976, 1601.4555051282566, 1858.507495513689, 1981.4848139483738, 7872.173421790708, 8503.02215245247, 8776.034636190014, 8776.42953208041, 8784.341174411913, 8296.12890646481, 8627.870581941275, 8638.568071596426, 8643.049264580906, 8645.876036115296, 2407.374082738492, 2464.4824586836694, 2467.950640987179, 2471.3080337505526, 2474.307193985687, 2386.570417324881, 2450.987745061281, 2451.1421002649017, 2454.5827209374447, 2463.3472129885595, 2413.806895448369, 2443.218170185541, 2453.6442215878687, 2459.225344016638, 2463.4210960424284, 2407.037185125946, 2425.9757402426017, 2434.3914021494625, 2437.073911913479, 2437.8293519453555, 2403.339081752532, 2407.1214006887535, 2415.5893498105365, 2423.7818596326238, 2433.720122185936, 8407.70573029947, 8537.0, 8630.568471576396, 8640.870386944202, 8642.222200001966, 5240.737963101829, 5414.3232095988005, 5427.968685949657, 5465.0, 5477.315335583052, 3427.7600567960253, 3535.257595904863, 3600.495930569696, 3647.5075864758182, 3649.4160934250394, 1020.8877023527406, 1041.7916416716687, 1044.2898828796415, 1052.6736711619346, 1053.4364115198332, 11072.501737421835, 11259.21185517014, 11266.662000139928, 11272.549098036068, 11285.266457680225, 4672.6261899047995, 4782.37829082224, 4788.809493553362, 4926.1379258629595, 4939.580135688435, 9982.201423886072, 10011.449370284705, 10029.949850250701, 10036.297459177787, 10038.447885366375, 5399.271098401744, 5413.512783849473, 5441.83674489766, 5450.182472629058, 5458.372287187024, 4271.9359209611885, 4466.419365482467, 4606.055758569485, 4861.538153875376, 4953.628477864175, 3993.980030099831, 4023.939640905389, 4072.470578824793, 4092.1201941582704, 4124.360724087771, 1317.4532568983957, 1347.883822398844, 1350.547566565192, 1360.6394305509048, 1364.781634938405, 831.3349320543662, 900.6127365232882, 920.070728564155, 943.9056094390506, 954.289054654287, 5673.489385955284, 5876.294844618587, 5951.642901425928, 5975.910361344583, 6083.422074902877, 456.52087237351407, 460.6222897224311, 463.88866671999153, 484.5638924967487, 486.64718079392156, 3853.402722578005, 3939.3106206414336, 3973.046468847456, 4036.1725846201457, 4058.6144316289906, 10139.391636501834, 10527.789444211227, 10547.578096876192, 10664.466776661136, 10688.572457101629, 10588.841167382496, 10589.046985771318, 10597.0, 10606.15150787935, 10609.681709548726, 7149.392301654314, 7993.360531157494, 8014.679412823421, 8103.189681031849, 8103.554304513191, 4805.927911081407, 5844.415558444121, 5998.0, 6005.099235114768, 6065.090236464565, 9469.47917864524, 9663.178629816544, 9758.80482390334, 9791.069848364394, 9841.409515429097, 9693.079157480028, 9892.703218903445, 9930.602775888883, 10092.697219083439, 10228.07947284748, 6629.436497897733, 6884.8967265490055, 7092.078029856086, 7507.774766757006, 7524.510906791041, 8179.959100204461, 8208.876866846882, 8274.875876861732, 8278.875816862632, 8281.54451505173, 7232.63836808147, 7328.853422931618, 7397.741079062207, 7436.18201997769, 7474.962625186839, 7133.928660713326, 7188.388986936169, 7294.817629559304, 7313.597752123685, 7355.374793142642, 6012.647154390227, 6948.270431604608, 6972.302769722987, 6980.825479362853, 7025.086738723933, 4757.35775670294, 4821.252705830602, 4869.269609558594, 4967.726775027264, 5098.5411312982005, 4870.522205466791, 4918.360613120271, 4951.603871690329, 5018.5232402921665, 5189.429162792015], "value": 4508.559320220343, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.37103051835305, 316.4383219784882, 321.7635038246888, 329.3626832079927, 329.55674617639283, 5046.066477701624, 5538.584606154544, 5788.536917046631, 5827.35899051104, 5854.443827836354, 4298.333758267466, 5206.479352064792, 5739.827805165842, 5753.482186603205, 5762.971185144069, 4345.913081738363, 4357.27887484445, 4368.344748287766, 4381.277089280265, 4392.155294776194, 3534.5228394166716, 3723.6487161554546, 4221.261279276115, 4382.408374869384, 4399.956000439986, 4292.227399068166, 4328.480582330125, 4329.913401731964, 4332.0, 4335.154644844257, 4318.9784051079705, 4323.22182007237, 4356.499002614708, 4358.019445624741, 4368.737875727466, 3453.9827300863585, 3525.4183059795105, 3779.6031416701126, 3784.791836448994, 3788.109794198361, 5059.494050594948, 5422.647527910713, 5430.321209848769, 5447.4824891635235, 5458.153986132156, 4799.904001919942, 4844.8304309349005, 4846.224604063333, 4858.927116093261, 4904.0, 5791.509650850133, 5993.340732519463, 6018.96990515049, 6025.728842202111, 6027.216461859974, 3970.4838371011583, 3987.464826041966, 3993.8617494014356, 4009.7594144351433, 4022.1955608878343, 6033.5173186145, 6307.747690092391, 6313.779017734403, 6408.93591064088, 6506.121673574102, 3388.9152771180916, 3656.6160553141535, 4021.3163762160502, 4023.4568333274633, 4031.9395209071886, 3489.4416893296952, 3922.2743792398496, 4154.958450415457, 4156.916861662749, 4183.0, 4781.928271075936, 4839.395075615548, 4856.271559266068, 4857.951420485821, 4893.657443978958, 5721.542276617861, 5797.826065218051, 6071.51427885768, 6119.84700382494, 6125.326214116443, 1419.7373485904934, 1433.8852891768634, 1436.2675035731852, 1437.4393986345333, 1442.3004842651305, 814.6659869453614, 912.9132732390412, 936.8266870628955, 936.840737074699, 948.5684013773716, 5331.813386531453, 5521.1442226454965, 5545.251391062155, 5582.91625625616, 5610.887782244332, 5512.476314750093, 6174.104754810527, 6181.887260293191, 6216.844578885564, 6240.500759939194, 4837.105135549935, 5297.761600727977, 5626.2967129108865, 5671.574631902624, 5706.201131841545, 3289.8190599517256, 3316.7512436567354, 3318.9170270742943, 3346.748993825473, 3348.263382055944, 5283.815066472655, 5543.223948647193, 6160.322364539897, 6239.845628558731, 6252.718627661675, 6491.67541622917, 6519.902201466982, 6525.4453371463005, 6548.443382312462, 6569.605823650596, 6073.514118870568, 6176.3514830943, 6612.8016159516255, 6620.271770105284, 6655.301193374626, 4720.150372932905, 5998.940010599838, 6022.049195894068, 6140.754369825157, 6337.4613157882095, 6122.877542449215, 6234.906476402767, 6237.906431403442, 6248.12526246335, 6292.370762923671, 5354.732263386737, 6310.905336420049, 6641.1034510342415, 6644.966775166094, 6657.0, 3833.789141597239, 3934.3705007199856, 4087.2642924273914, 4129.958700412957, 4143.150654115839, 5991.281046274475, 6310.905336420049, 6326.746930122744, 6328.272248691275, 6423.614583125028, 5782.884342313214, 5908.527317814479, 6100.115483254992, 6125.693715314305, 6134.631922084689, 6055.6063855849225, 6060.939390606214, 6118.694065296806, 6119.571629985859, 6122.785702500391, 5219.164933610604, 5561.332640083216, 5978.581499295182, 5986.623076692472, 6015.4586087252355, 5843.2403787507355, 6061.272647282354, 6073.171828396524, 6077.51379889599, 6115.3578874217565, 5925.022371308686, 5936.1095835625, 5947.6431414115295, 5968.850778730566, 6023.337432882292], "value": 4422.422877041545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9086364204475, 732.5677850068458, 736.1644533454528, 737.8450525389668, 749.995006691034, 14784.334704938277, 15272.0, 15285.0, 15296.235188240586, 15327.386904523819, 13952.651183720413, 14048.71902561948, 14049.0, 14051.578452646412, 14068.648283792885, 718.0880282041804, 872.2150064941571, 906.9932375063707, 912.8493798523235, 914.5838643417262, 903.6882275614902, 913.0641092879819, 914.6570036236411, 915.4370062411635, 919.9862002069941, 968.9563969621383, 977.8484334928062, 980.7646164920403, 980.8430651095827, 981.0679854138597, 751.5077624156187, 783.7139444102918, 960.9759756006085, 968.2350942755215, 973.1971123822804, 716.6703316474428, 723.5477826358526, 725.521156037013, 728.3990707666144, 729.8832186850078, 14719.705605887822, 14762.70474590513, 14780.778288325684, 14791.40834366624, 14792.630184245372, 12344.81482777759, 12561.101170543496, 12649.304288264142, 12887.097903146783, 12998.740025199537, 9927.305088643798, 9975.501224938724, 10118.0, 10148.94925525375, 10559.313644613076, 3508.596511401196, 3513.5256740339983, 3519.3665140274998, 3601.9099522511883, 3604.008897553194, 74453.62773186153, 75167.62416187939, 75335.24664753392, 78640.0, 79229.60385198037, 9566.760830979141, 9694.951525242328, 9701.07839755222, 9719.319647624598, 9939.403635781877, 20325.695114573293, 22340.0, 22341.329760107223, 22346.329610111723, 22387.104515819512, 7459.888101678479, 7556.848863022708, 7598.088229412506, 7614.2766437188375, 7633.6564854581675, 4052.2908491013955, 4150.771707556113, 4150.896227594334, 4157.584241575818, 4163.521195062546, 3475.3396854597545, 3572.4819901114192, 3574.4995700601935, 3575.3028159508644, 3575.481555174485, 632.5666918161074, 648.3743187823678, 652.1131261484378, 653.6274323635578, 657.6678777217475, 422.5837550013234, 570.8601392658742, 574.9507149452267, 581.4679568195054, 588.9322727886263, 7836.882446763303, 8009.559474228857, 8024.7191348303695, 8029.116797152308, 8107.351411887153, 565.3780841074785, 579.7188363643629, 586.2613107484515, 594.8869714754182, 604.4137186928672, 3577.159367548624, 3676.761010534307, 3785.413260944558, 3796.335641262823, 3802.3535998880257, 80270.0, 81785.59107204546, 81852.18147818565, 82074.58962705148, 82373.58813205895, 61669.691651541456, 62205.06692399618, 62395.0, 63290.68354658289, 63461.68269158625, 62074.37925620686, 63445.41386465283, 64496.355036449975, 65213.67393163004, 65775.99999999904, 6619.404253617197, 7298.4526160539235, 7402.629868506661, 7475.962620186864, 7833.843323133391, 40545.59454405536, 41447.58552414558, 41978.37032444455, 42111.578884210765, 42461.0, 40077.398839018024, 56672.0, 58081.70959145177, 58130.83738325295, 58249.0, 8181.468204566684, 9784.266180036668, 10127.189824814144, 10442.269041167045, 10611.204159688208, 11478.139139564399, 11530.884691152982, 11570.826437603611, 11681.240719353214, 11989.520419183134, 11245.325280483197, 11364.772704545698, 11510.194286399872, 11527.0, 11758.647240582795, 11533.135014874086, 11586.826197607212, 11808.881911180779, 12128.332941687939, 12472.87527124717, 11268.211225214156, 11302.152338574804, 11376.431178441211, 11537.868884238585, 11617.6514704559, 9525.571349289437, 9526.761830954281, 9547.084553194203, 9750.024997500477, 9937.602495900082, 9464.337496375463, 9817.361871478332, 9857.11285984265, 9919.40483570988, 10002.699919002442], "value": 6702.2765834080565, "unit": "score"}], "name": "dromaeo_css", "value": 7116.298301843417, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 113.0, 113.0, 113.0, 112.0, 118.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 143.0, 170.0, 119.0, 143.0, 142.0, 119.0, 172.0, 120.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 187.0, 184.0, 186.0, 187.0, 256.0, 235.0, 184.0, 189.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 86.0, 89.0, 55.0, 59.0, 63.0, 78.0, 85.0, 54.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 134.0, 77.0, 79.0, 80.0, 125.0, 79.0, 76.0, 121.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 84.0, 89.0, 131.0, 89.0, 85.0, 88.0, 85.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 151.0, 91.0, 130.0, 149.0, 93.0, 93.0, 92.0, 93.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 98.0, 121.0, 86.0, 85.0, 84.0, 89.0, 84.0, 85.0, 86.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 72.0, 59.0, 56.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 76.0, 80.0, 72.0, 72.0, 68.0, 69.0, 69.0, 75.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0, 87.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 143.0, 142.0, 142.0, 138.0, 140.0, 143.0, 145.0, 181.0, 142.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 171.0, 171.0, 182.0, 206.0, 225.0, 223.0, 175.0, 167.0], "value": 185.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 64.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1523.8, "alertThreshold": 2.0}]} 18:51:35 INFO - Return code: 0 18:51:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:51:35 ERROR - # TBPL SUCCESS # 18:51:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:35 INFO - 2016-04-13 18:51:35.805 screenresolution[1957:16082] starting screenresolution argv=screenresolution get 18:51:35 INFO - 2016-04-13 18:51:35.807 screenresolution[1957:16082] Display 0: 1600x1200x32@60 18:51:35 INFO - 2016-04-13 18:51:35.813 screenresolution[1958:16085] starting screenresolution argv=screenresolution list 18:51:35 INFO - Available Modes on Display 0 18:51:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:36 INFO - Intel Iris: 18:51:36 INFO - Chipset Model: Intel Iris 18:51:36 INFO - Type: GPU 18:51:36 INFO - Bus: Built-In 18:51:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:36 INFO - Vendor: Intel (0x8086) 18:51:36 INFO - Device ID: 0x0a2e 18:51:36 INFO - Revision ID: 0x0009 18:51:36 INFO - Displays: 18:51:36 INFO - Display: 18:51:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:36 INFO - Display Serial Number: 1600x1200 60 18:51:36 INFO - Main Display: Yes 18:51:36 INFO - Mirror: Off 18:51:36 INFO - Online: Yes 18:51:36 INFO - Rotation: Supported 18:51:36 INFO - Return code: 0 18:51:36 INFO - Running post-action listener: _resource_record_post_action 18:51:36 INFO - Running post-run listener: _resource_record_post_run 18:51:37 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 552960; Write bytes: 693913088; Read time: 6; Write time: 2639 18:51:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382831104; Write bytes: 376768000; Read time: 28514; Write time: 676 18:51:37 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 552960; Write bytes: 296040960; Read time: 6; Write time: 1876 18:51:37 INFO - Running post-run listener: _upload_blobber_files 18:51:37 INFO - Blob upload gear active. 18:51:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:51:37 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:51:37 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:51:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:51:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:51:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:38 INFO - (blobuploader) - INFO - Done attempting. 18:51:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:51:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:51:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:39 INFO - (blobuploader) - INFO - Done attempting. 18:51:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:39 INFO - Return code: 0 18:51:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0853ce7d235255c4690d6a813f9c6c0353c63729ec2044d356bd8e4c5330454a2392a84044e11674c2ec05ece25a6574074bcb8a7359fd086f53090bf7515c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ba5b084e43f747c051dd4039e47557c25e9b58c4f3f5fa59d9226a78c51bbecec95117ac28a466c9636668be01c14532ac244f1538b8fe37cf8a985cd403fdf"} 18:51:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:39 INFO - Contents: 18:51:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0853ce7d235255c4690d6a813f9c6c0353c63729ec2044d356bd8e4c5330454a2392a84044e11674c2ec05ece25a6574074bcb8a7359fd086f53090bf7515c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ba5b084e43f747c051dd4039e47557c25e9b58c4f3f5fa59d9226a78c51bbecec95117ac28a466c9636668be01c14532ac244f1538b8fe37cf8a985cd403fdf"} 18:51:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:39 INFO - Copying logs to upload dir... 18:51:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.271217 ========= master_lag: 2.67 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-13 18:51:42.377254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-13 18:51:42.380634) ========= 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.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Firefox/sha512/0853ce7d235255c4690d6a813f9c6c0353c63729ec2044d356bd8e4c5330454a2392a84044e11674c2ec05ece25a6574074bcb8a7359fd086f53090bf7515c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ba5b084e43f747c051dd4039e47557c25e9b58c4f3f5fa59d9226a78c51bbecec95117ac28a466c9636668be01c14532ac244f1538b8fe37cf8a985cd403fdf"} build_url:https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/UEDos2_XSPuIx23qWNiIQQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0853ce7d235255c4690d6a813f9c6c0353c63729ec2044d356bd8e4c5330454a2392a84044e11674c2ec05ece25a6574074bcb8a7359fd086f53090bf7515c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ba5b084e43f747c051dd4039e47557c25e9b58c4f3f5fa59d9226a78c51bbecec95117ac28a466c9636668be01c14532ac244f1538b8fe37cf8a985cd403fdf"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-13 18:51:42.693018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-13 18:51:42.693444) ========= 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.UNREqd8UOH/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.ahAIGv5EgP/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005312 ========= master_lag: 3.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-13 18:51:45.715939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-13 18:51:45.716257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-13 18:51:45.844435) ========= ========= Total master_lag: 6.66 =========