builder: fx-team_yosemite_test-chromez slave: t-yosemite-r5-0073 starttime: 1446457968.63 results: success (0) buildid: 20151102000831 builduid: 6ba3099163464737b29fc67280b93594 revision: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.632148) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.632933) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.633547) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005892 basedir: '/builds/slave/test' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.697020) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.697715) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.726127) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.726849) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.030148 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.777703) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.778070) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.778531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.778850) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False --2015-11-02 01:52:48-- 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% 1.26G=0s 2015-11-02 01:52:48 (1.26 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130689 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.926429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:48.926808) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.069179 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.011943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.012376) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False 2015-11-02 01:52:49,098 truncating revision to first 12 chars 2015-11-02 01:52:49,098 Setting DEBUG logging. 2015-11-02 01:52:49,098 attempt 1/10 2015-11-02 01:52:49,098 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/83ee1cb04baa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-02 01:52:49,543 unpacking tar archive at: fx-team-83ee1cb04baa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.717751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.748022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.748408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.762876) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.763336) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:52:49.763848) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 28 secs) (at 2015-11-02 01:52:49.764165) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite chromez --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'chromez', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False 01:52:50 INFO - MultiFileLogger online at 20151102 01:52:50 in /builds/slave/test 01:52:50 INFO - Run as scripts/scripts/talos_script.py --suite chromez --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:52:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:52:50 INFO - {'append_to_log': False, 01:52:50 INFO - 'base_work_dir': '/builds/slave/test', 01:52:50 INFO - 'blob_upload_branch': 'Fx-Team', 01:52:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:52:50 INFO - 'branch': 'Fx-Team', 01:52:50 INFO - 'buildbot_json_path': 'buildprops.json', 01:52:50 INFO - 'config_files': ('talos/mac_config.py',), 01:52:50 INFO - 'default_actions': ('clobber', 01:52:50 INFO - 'read-buildbot-config', 01:52:50 INFO - 'download-and-extract', 01:52:50 INFO - 'populate-webroot', 01:52:50 INFO - 'create-virtualenv', 01:52:50 INFO - 'install', 01:52:50 INFO - 'run-tests'), 01:52:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:52:50 INFO - 'download_minidump_stackwalk': True, 01:52:50 INFO - 'download_symbols': 'ondemand', 01:52:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:52:50 INFO - 'tooltool.py': '/tools/tooltool.py', 01:52:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:52:50 INFO - '/tools/misc-python/virtualenv.py')}, 01:52:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:52:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:52:50 INFO - 'installer_path': 'installer.exe', 01:52:50 INFO - 'log_level': 'info', 01:52:50 INFO - 'log_name': 'talos', 01:52:50 INFO - 'log_to_console': True, 01:52:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:52:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:52:50 INFO - 'opt_config_files': (), 01:52:50 INFO - 'pip_index': False, 01:52:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:52:50 INFO - 'cmd': ('bash', 01:52:50 INFO - '-c', 01:52:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:52:50 INFO - 'enabled': True, 01:52:50 INFO - 'halt_on_failure': False, 01:52:50 INFO - 'name': 'check_screen_resolution'},), 01:52:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:52:50 INFO - 'cmd': ('bash', 01:52:50 INFO - '-c', 01:52:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:52:50 INFO - 'enabled': True, 01:52:50 INFO - 'halt_on_failure': False, 01:52:50 INFO - 'name': 'check_screen_resolution'},), 01:52:50 INFO - 'run_cmd_checks_enabled': True, 01:52:50 INFO - 'sps_profile': False, 01:52:50 INFO - 'sps_profile_interval': 0, 01:52:50 INFO - 'suite': 'chromez', 01:52:50 INFO - 'system_bits': '64', 01:52:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:52:50 INFO - 'title': 't-yosemite-r5-0073', 01:52:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:52:50 INFO - 'use_talos_json': True, 01:52:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:52:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:52:50 INFO - 'work_dir': 'build'} 01:52:50 INFO - ##### 01:52:50 INFO - ##### Running clobber step. 01:52:50 INFO - ##### 01:52:50 INFO - Running pre-action listener: _resource_record_pre_action 01:52:50 INFO - Running main action method: clobber 01:52:50 INFO - rmtree: /builds/slave/test/build 01:52:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:52:53 INFO - Running post-action listener: _resource_record_post_action 01:52:53 INFO - ##### 01:52:53 INFO - ##### Running read-buildbot-config step. 01:52:53 INFO - ##### 01:52:53 INFO - Running pre-action listener: _resource_record_pre_action 01:52:53 INFO - Running main action method: read_buildbot_config 01:52:53 INFO - Using buildbot properties: 01:52:53 INFO - { 01:52:53 INFO - "properties": { 01:52:53 INFO - "buildnumber": 1233, 01:52:53 INFO - "product": "firefox", 01:52:53 INFO - "script_repo_revision": "production", 01:52:53 INFO - "builddir": "fx-team_yosemite_test-chromez", 01:52:53 INFO - "repository": "", 01:52:53 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10 fx-team talos chromez", 01:52:53 INFO - "buildid": "20151102000831", 01:52:53 INFO - "slavename": "t-yosemite-r5-0073", 01:52:53 INFO - "pgo_build": "False", 01:52:53 INFO - "basedir": "/builds/slave/test", 01:52:53 INFO - "project": "", 01:52:53 INFO - "platform": "yosemite", 01:52:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:52:53 INFO - "slavebuilddir": "test", 01:52:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:52:53 INFO - "branch": "fx-team", 01:52:53 INFO - "repo_path": "integration/fx-team", 01:52:53 INFO - "stage_platform": "macosx64", 01:52:53 INFO - "builduid": "6ba3099163464737b29fc67280b93594", 01:52:53 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:52:53 INFO - }, 01:52:53 INFO - "sourcestamp": { 01:52:53 INFO - "repository": "", 01:52:53 INFO - "hasPatch": false, 01:52:53 INFO - "project": "", 01:52:53 INFO - "branch": "fx-team-macosx64-talos", 01:52:53 INFO - "changes": [ 01:52:53 INFO - { 01:52:53 INFO - "category": null, 01:52:53 INFO - "files": [ 01:52:53 INFO - { 01:52:53 INFO - "url": null, 01:52:53 INFO - "name": "https://queue.taskcluster.net/v1/task/SEWb_oC2SvG-MOegMJ2e6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 01:52:53 INFO - } 01:52:53 INFO - ], 01:52:53 INFO - "repository": "", 01:52:53 INFO - "rev": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2", 01:52:53 INFO - "who": "cbook@mozilla.com", 01:52:53 INFO - "when": 1446457638, 01:52:53 INFO - "number": 6608063, 01:52:53 INFO - "comments": "Bug 1205843 - Part 2: Disable maintenance service on WinXP < SP3 - r=rstrong\n\nAttempt to get around the fact that XP SP2 (and below) systems won't be able to\nhandle Authenticode signatues with SHA-2 digests by disabling the maintenance\nservice, the only thing on our update path that uses Authenticode, on systems\nrunning WinXP below SP3.\n\nThis patch both prevents the service from being included in new installations,\nand prevents the service from being used where it is already installed.", 01:52:53 INFO - "project": "", 01:52:53 INFO - "at": "Mon 02 Nov 2015 01:47:18", 01:52:53 INFO - "branch": "fx-team-macosx64-talos", 01:52:53 INFO - "revlink": "", 01:52:53 INFO - "properties": [ 01:52:53 INFO - [ 01:52:53 INFO - "buildid", 01:52:53 INFO - "20151102000630", 01:52:53 INFO - "Change" 01:52:53 INFO - ], 01:52:53 INFO - [ 01:52:53 INFO - "builduid", 01:52:53 INFO - "07e935540f0a43198a540945d6001681", 01:52:53 INFO - "Change" 01:52:53 INFO - ], 01:52:53 INFO - [ 01:52:53 INFO - "pgo_build", 01:52:53 INFO - "False", 01:52:53 INFO - "Change" 01:52:53 INFO - ] 01:52:53 INFO - ], 01:52:53 INFO - "revision": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2" 01:52:53 INFO - }, 01:52:53 INFO - { 01:52:53 INFO - "category": null, 01:52:53 INFO - "files": [ 01:52:53 INFO - { 01:52:53 INFO - "url": null, 01:52:53 INFO - "name": "https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 01:52:53 INFO - } 01:52:53 INFO - ], 01:52:53 INFO - "repository": "", 01:52:53 INFO - "rev": "83ee1cb04baaedfa97074a651ce34c58973ff5e4", 01:52:53 INFO - "who": "cbook@mozilla.com", 01:52:53 INFO - "when": 1446457890, 01:52:53 INFO - "number": 6608087, 01:52:53 INFO - "comments": "Bug 1220407 - include sys/resource.h for struct rusage and getrusage() on all unices, not linux only. r=yoric", 01:52:53 INFO - "project": "", 01:52:53 INFO - "at": "Mon 02 Nov 2015 01:51:30", 01:52:53 INFO - "branch": "fx-team-macosx64-talos", 01:52:53 INFO - "revlink": "", 01:52:53 INFO - "properties": [ 01:52:53 INFO - [ 01:52:53 INFO - "buildid", 01:52:53 INFO - "20151102000831", 01:52:53 INFO - "Change" 01:52:53 INFO - ], 01:52:53 INFO - [ 01:52:53 INFO - "builduid", 01:52:53 INFO - "6ba3099163464737b29fc67280b93594", 01:52:53 INFO - "Change" 01:52:53 INFO - ], 01:52:53 INFO - [ 01:52:53 INFO - "pgo_build", 01:52:53 INFO - "False", 01:52:53 INFO - "Change" 01:52:53 INFO - ] 01:52:53 INFO - ], 01:52:53 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:52:53 INFO - } 01:52:53 INFO - ], 01:52:53 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:52:53 INFO - } 01:52:53 INFO - } 01:52:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 01:52:53 INFO - Running post-action listener: _resource_record_post_action 01:52:53 INFO - ##### 01:52:53 INFO - ##### Running download-and-extract step. 01:52:53 INFO - ##### 01:52:53 INFO - Running pre-action listener: _resource_record_pre_action 01:52:53 INFO - Running main action method: download_and_extract 01:52:53 INFO - mkdir: /builds/slave/test/build/tests 01:52:53 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')]} 01:52:53 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:52:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/test_packages.json 01:52:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/test_packages.json 01:52:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 01:52:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 01:52:54 INFO - Downloaded 1149 bytes. 01:52:54 INFO - Reading from file /builds/slave/test/build/test_packages.json 01:52:54 INFO - Using the following test package requirements: 01:52:54 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 01:52:54 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 01:52:54 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'jsshell-mac.zip'], 01:52:54 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 01:52:54 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 01:52:54 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 01:52:54 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 01:52:54 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 01:52:54 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 01:52:54 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 01:52:54 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 01:52:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:54 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:52:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:52:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:52:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:52:55 INFO - Downloaded 17452682 bytes. 01:52:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 01:52:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:52:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 01:53:01 INFO - Return code: 0 01:53:01 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip', u'firefox-45.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:01 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:53:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:53:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:53:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:53:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:53:02 INFO - Downloaded 17452682 bytes. 01:53:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 01:53:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 01:53:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 01:53:08 INFO - Return code: 0 01:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:08 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:53:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 01:53:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 01:53:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 01:53:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:53:09 INFO - Downloaded 11131307 bytes. 01:53:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] in /builds/slave/test/build/tests 01:53:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 01:53:09 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 01:53:10 INFO - Return code: 0 01:53:10 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')]} 01:53:10 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:53:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 01:53:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 01:53:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 01:53:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg'}, attempt #1 01:53:14 INFO - Downloaded 108534348 bytes. 01:53:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 01:53:14 INFO - mkdir: /builds/slave/test/properties 01:53:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:53:14 INFO - Writing to file /builds/slave/test/properties/build_url 01:53:14 INFO - Contents: 01:53:14 INFO - build_url:https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 01:53:14 INFO - Running post-action listener: _resource_record_post_action 01:53:14 INFO - Running post-action listener: set_extra_try_arguments 01:53:14 INFO - ##### 01:53:14 INFO - ##### Running populate-webroot step. 01:53:14 INFO - ##### 01:53:14 INFO - Running pre-action listener: _resource_record_pre_action 01:53:14 INFO - Running main action method: populate_webroot 01:53:14 INFO - Guessing talos json url... 01:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:14 INFO - https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt matches https://queue.taskcluster.net 01:53:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt 01:53:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt 01:53:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt to /builds/slave/test/build/firefox-45.0a1.en-US.mac.txt 01:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.txt'}, attempt #1 01:53:14 INFO - Downloaded 103 bytes. 01:53:14 INFO - Reading from file /builds/slave/test/build/firefox-45.0a1.en-US.mac.txt 01:53:14 INFO - Contents: 01:53:14 INFO - 20151102000831 01:53:14 INFO - https://hg.mozilla.org/integration/fx-team/rev/83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:14 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json 01:53:14 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json to /builds/slave/test/build/talos.json 01:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1 01:53:15 INFO - Downloaded 4264 bytes. 01:53:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:53:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:53:15 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:53:15 INFO - '../fennec_ids.txt'], 01:53:15 INFO - 'tests': ['tcheck2']}, 01:53:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:53:15 INFO - '--tppagecycles', 01:53:15 INFO - '7'], 01:53:15 INFO - 'tests': ['tsvgm']}}, 01:53:15 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:53:15 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:53:15 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:53:15 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:53:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:53:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:53:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'tests': ['damp', 'tps']}, 01:53:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['damp', 'tps']}, 01:53:15 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:53:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:53:15 INFO - 'other': {'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'other_l64': {'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'other_nol64': {'tests': ['a11yr', 01:53:15 INFO - 'ts_paint', 01:53:15 INFO - 'tpaint', 01:53:15 INFO - 'sessionrestore', 01:53:15 INFO - 'sessionrestore_no_auto_restore']}, 01:53:15 INFO - 'svgr': {'tests': ['tsvgx', 01:53:15 INFO - 'tsvgr_opacity', 01:53:15 INFO - 'tart', 01:53:15 INFO - 'tscrollx', 01:53:15 INFO - 'cart']}, 01:53:15 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['tsvgx', 01:53:15 INFO - 'tsvgr_opacity', 01:53:15 INFO - 'tart', 01:53:15 INFO - 'tscrollx', 01:53:15 INFO - 'cart']}, 01:53:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'tests': ['tp5o']}, 01:53:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'talos_options': ['--e10s'], 01:53:15 INFO - 'tests': ['tp5o']}, 01:53:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'talos_options': ['--xperf_path', 01:53:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:53:15 INFO - 'tests': ['tp5n']}, 01:53:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:53:15 INFO - 'talos_options': ['--e10s', 01:53:15 INFO - '--xperf_path', 01:53:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 01:53:15 INFO - 'C:/slave/talos-data/talos/xperf.config'], 01:53:15 INFO - 'tests': ['tp5n']}}, 01:53:15 INFO - 'talos.zip': {'path': '', 01:53:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:53:15 INFO - Running post-action listener: _resource_record_post_action 01:53:15 INFO - ##### 01:53:15 INFO - ##### Running create-virtualenv step. 01:53:15 INFO - ##### 01:53:15 INFO - Running pre-action listener: _resource_record_pre_action 01:53:15 INFO - Running main action method: create_virtualenv 01:53:15 INFO - Creating virtualenv /builds/slave/test/build/venv 01:53:15 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 01:53:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:53:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:53:15 INFO - Using real prefix '/tools/python27' 01:53:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:53:16 INFO - Installing distribute.............................................................................................................................................................................................done. 01:53:20 INFO - Installing pip.................done. 01:53:20 INFO - Return code: 0 01:53:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:53:20 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')]} 01:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:20 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')]} 01:53:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:20 INFO - 'HOME': '/Users/cltbld', 01:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:20 INFO - 'LOGNAME': 'cltbld', 01:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:20 INFO - 'MOZ_NO_REMOTE': '1', 01:53:20 INFO - 'NO_EM_RESTART': '1', 01:53:20 INFO - 'PAGER': '/bin/cat', 01:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:20 INFO - 'PWD': '/builds/slave/test', 01:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:20 INFO - 'SHELL': '/bin/bash', 01:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:20 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:20 INFO - 'USER': 'cltbld', 01:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:20 INFO - 'XPC_FLAGS': '0x0', 01:53:20 INFO - 'XPC_SERVICE_NAME': '0', 01:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:53:21 INFO - Cleaning up... 01:53:21 INFO - Return code: 0 01:53:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:21 INFO - 'HOME': '/Users/cltbld', 01:53:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:21 INFO - 'LOGNAME': 'cltbld', 01:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:21 INFO - 'MOZ_NO_REMOTE': '1', 01:53:21 INFO - 'NO_EM_RESTART': '1', 01:53:21 INFO - 'PAGER': '/bin/cat', 01:53:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:21 INFO - 'PWD': '/builds/slave/test', 01:53:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:21 INFO - 'SHELL': '/bin/bash', 01:53:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:21 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:21 INFO - 'USER': 'cltbld', 01:53:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:21 INFO - 'XPC_FLAGS': '0x0', 01:53:21 INFO - 'XPC_SERVICE_NAME': '0', 01:53:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:22 INFO - Downloading/unpacking psutil>=0.7.1 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53:24 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 01:53:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:53:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:53:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:53:24 INFO - Installing collected packages: psutil 01:53:24 INFO - Running setup.py install for psutil 01:53:25 INFO - building 'psutil._psutil_osx' extension 01:53:25 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 01:53:26 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 01:53:26 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 01:53:27 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 01:53:27 INFO - building 'psutil._psutil_posix' extension 01:53:27 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 01:53:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:53:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:53:27 INFO - ^ 01:53:27 INFO - 1 warning generated. 01:53:27 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 01:53:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:53:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:53:27 INFO - Successfully installed psutil 01:53:27 INFO - Cleaning up... 01:53:27 INFO - Return code: 0 01:53:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:27 INFO - 'HOME': '/Users/cltbld', 01:53:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:27 INFO - 'LOGNAME': 'cltbld', 01:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:27 INFO - 'MOZ_NO_REMOTE': '1', 01:53:27 INFO - 'NO_EM_RESTART': '1', 01:53:27 INFO - 'PAGER': '/bin/cat', 01:53:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:27 INFO - 'PWD': '/builds/slave/test', 01:53:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:27 INFO - 'SHELL': '/bin/bash', 01:53:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:27 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:27 INFO - 'USER': 'cltbld', 01:53:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:27 INFO - 'XPC_FLAGS': '0x0', 01:53:27 INFO - 'XPC_SERVICE_NAME': '0', 01:53:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:53:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:53:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:53:30 INFO - Installing collected packages: mozsystemmonitor 01:53:30 INFO - Running setup.py install for mozsystemmonitor 01:53:31 INFO - Successfully installed mozsystemmonitor 01:53:31 INFO - Cleaning up... 01:53:31 INFO - Return code: 0 01:53:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:31 INFO - 'HOME': '/Users/cltbld', 01:53:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:31 INFO - 'LOGNAME': 'cltbld', 01:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:31 INFO - 'MOZ_NO_REMOTE': '1', 01:53:31 INFO - 'NO_EM_RESTART': '1', 01:53:31 INFO - 'PAGER': '/bin/cat', 01:53:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:31 INFO - 'PWD': '/builds/slave/test', 01:53:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:31 INFO - 'SHELL': '/bin/bash', 01:53:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:31 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:31 INFO - 'USER': 'cltbld', 01:53:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:31 INFO - 'XPC_FLAGS': '0x0', 01:53:31 INFO - 'XPC_SERVICE_NAME': '0', 01:53:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:31 INFO - Downloading/unpacking blobuploader==1.2.4 01:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:34 INFO - Downloading blobuploader-1.2.4.tar.gz 01:53:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:53:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:53:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:53:34 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 01:53:34 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 01:53:34 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 01:53:34 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 01:53:34 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 01:53:34 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 01:53:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:53:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:53:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:53:35 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 01:53:35 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 01:53:35 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 01:53:35 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 01:53:35 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 01:53:35 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 01:53:35 INFO - Downloading docopt-0.6.1.tar.gz 01:53:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:53:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:53:35 INFO - Installing collected packages: blobuploader, requests, docopt 01:53:35 INFO - Running setup.py install for blobuploader 01:53:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:53:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:53:35 INFO - Running setup.py install for requests 01:53:36 INFO - Running setup.py install for docopt 01:53:36 INFO - Successfully installed blobuploader requests docopt 01:53:36 INFO - Cleaning up... 01:53:36 INFO - Return code: 0 01:53:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:36 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')]} 01:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:36 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')]} 01:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 01:53:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:36 INFO - 'HOME': '/Users/cltbld', 01:53:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:36 INFO - 'LOGNAME': 'cltbld', 01:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:36 INFO - 'MOZ_NO_REMOTE': '1', 01:53:36 INFO - 'NO_EM_RESTART': '1', 01:53:36 INFO - 'PAGER': '/bin/cat', 01:53:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:36 INFO - 'PWD': '/builds/slave/test', 01:53:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:36 INFO - 'SHELL': '/bin/bash', 01:53:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:36 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:36 INFO - 'USER': 'cltbld', 01:53:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:36 INFO - 'XPC_FLAGS': '0x0', 01:53:36 INFO - 'XPC_SERVICE_NAME': '0', 01:53:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-z4zvYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-sQ01H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-IwO2QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-_n748k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-zxRLn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-kV86B6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:53:37 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-GnSHIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-7lw9Qc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-tcsDeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-o19T9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-n5JJGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-VnWSRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-yysA9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:53:38 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-K0Fzyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:53:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:39 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-0LGGik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:53:39 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-nszy8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:53:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:53:39 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-1jXgB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:53:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:53:39 INFO - Running setup.py install for manifestparser 01:53:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:53:39 INFO - Running setup.py install for mozcrash 01:53:39 INFO - Running setup.py install for mozdebug 01:53:39 INFO - Running setup.py install for mozdevice 01:53:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Running setup.py install for mozfile 01:53:40 INFO - Running setup.py install for mozhttpd 01:53:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Running setup.py install for mozinfo 01:53:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Running setup.py install for mozInstall 01:53:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:53:40 INFO - Running setup.py install for mozleak 01:53:41 INFO - Running setup.py install for mozlog 01:53:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:53:41 INFO - Running setup.py install for moznetwork 01:53:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:53:41 INFO - Running setup.py install for mozprocess 01:53:41 INFO - Running setup.py install for mozprofile 01:53:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:53:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:53:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:53:41 INFO - Running setup.py install for mozrunner 01:53:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:53:42 INFO - Running setup.py install for mozscreenshot 01:53:42 INFO - Running setup.py install for moztest 01:53:42 INFO - Running setup.py install for mozversion 01:53:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:53:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:53:42 INFO - Cleaning up... 01:53:42 INFO - Return code: 0 01:53:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:42 INFO - 'HOME': '/Users/cltbld', 01:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:42 INFO - 'LOGNAME': 'cltbld', 01:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:42 INFO - 'MOZ_NO_REMOTE': '1', 01:53:42 INFO - 'NO_EM_RESTART': '1', 01:53:42 INFO - 'PAGER': '/bin/cat', 01:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:42 INFO - 'PWD': '/builds/slave/test', 01:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:42 INFO - 'SHELL': '/bin/bash', 01:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:42 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:42 INFO - 'USER': 'cltbld', 01:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:42 INFO - 'XPC_FLAGS': '0x0', 01:53:42 INFO - 'XPC_SERVICE_NAME': '0', 01:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-gHI_0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:53:43 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-Da11wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:53:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-XXe3kT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:53:43 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-UVHM7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:53:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-OhQOJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:53:43 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-zx9eNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:53:43 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)) 01:53:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:53:43 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-SnIfag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:53:44 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-lWcOUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:53:44 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-LP2IkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:53:44 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-vVY2Xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-jetSOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:53:44 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-hg6QFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:53:44 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-TicyjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 01:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:53:44 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-LgOxiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:53:45 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)) 01:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:45 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-c0tSsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:45 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)) 01:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:53:45 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-qTY61m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:53:45 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)) 01:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:53:45 INFO - Running setup.py (path:/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/pip-s5IsQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:53:45 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)) 01:53:45 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:53:45 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:53:45 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)) 01:53:45 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:53:45 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:53:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:53:45 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 01:53:45 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 01:53:45 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 01:53:45 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 01:53:45 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 01:53:45 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 01:53:48 INFO - Downloading blessings-1.5.1.tar.gz 01:53:48 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 01:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:53:48 INFO - Installing collected packages: blessings 01:53:48 INFO - Running setup.py install for blessings 01:53:48 INFO - Successfully installed blessings 01:53:48 INFO - Cleaning up... 01:53:48 INFO - Return code: 0 01:53:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:53:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:49 INFO - Reading from file tmpfile_stdout 01:53:49 INFO - Current package versions: 01:53:49 INFO - blessings == 1.5.1 01:53:49 INFO - blobuploader == 1.2.4 01:53:49 INFO - docopt == 0.6.1 01:53:49 INFO - manifestparser == 1.1 01:53:49 INFO - mozInstall == 1.12 01:53:49 INFO - mozcrash == 0.16 01:53:49 INFO - mozdebug == 0.1 01:53:49 INFO - mozdevice == 0.47 01:53:49 INFO - mozfile == 1.2 01:53:49 INFO - mozhttpd == 0.7 01:53:49 INFO - mozinfo == 0.9 01:53:49 INFO - mozleak == 0.1 01:53:49 INFO - mozlog == 3.0 01:53:49 INFO - moznetwork == 0.27 01:53:49 INFO - mozprocess == 0.22 01:53:49 INFO - mozprofile == 0.27 01:53:49 INFO - mozrunner == 6.11 01:53:49 INFO - mozscreenshot == 0.1 01:53:49 INFO - mozsystemmonitor == 0.0 01:53:49 INFO - moztest == 0.7 01:53:49 INFO - mozversion == 1.4 01:53:49 INFO - psutil == 3.1.1 01:53:49 INFO - requests == 1.2.3 01:53:49 INFO - wsgiref == 0.1.2 01:53:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:49 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')]} 01:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:49 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')]} 01:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x108d70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ddd228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb40ca3750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/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.hn9MADnbEH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 01:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 01:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:49 INFO - 'HOME': '/Users/cltbld', 01:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:49 INFO - 'LOGNAME': 'cltbld', 01:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:49 INFO - 'MOZ_NO_REMOTE': '1', 01:53:49 INFO - 'NO_EM_RESTART': '1', 01:53:49 INFO - 'PAGER': '/bin/cat', 01:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:49 INFO - 'PWD': '/builds/slave/test', 01:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:49 INFO - 'SHELL': '/bin/bash', 01:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:53:49 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:49 INFO - 'USER': 'cltbld', 01:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:49 INFO - 'XPC_FLAGS': '0x0', 01:53:49 INFO - 'XPC_SERVICE_NAME': '0', 01:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:49 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 1)) 01:53:49 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 2)) 01:53:49 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 3)) 01:53:49 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 4)) 01:53:49 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 5)) 01:53:49 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 6)) 01:53:49 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 7)) 01:53:49 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 8)) 01:53:49 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:53:49 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 3)) 01:53:49 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:53:49 INFO - Cleaning up... 01:53:49 INFO - Return code: 0 01:53:49 INFO - Running post-action listener: _resource_record_post_action 01:53:49 INFO - Running post-action listener: _start_resource_monitoring 01:53:49 INFO - Starting resource monitoring. 01:53:49 INFO - ##### 01:53:49 INFO - ##### Running install step. 01:53:49 INFO - ##### 01:53:49 INFO - Running pre-action listener: _resource_record_pre_action 01:53:49 INFO - Running main action method: install 01:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:49 INFO - Reading from file tmpfile_stdout 01:53:49 INFO - Detecting whether we're running mozinstall >=1.0... 01:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:53:50 INFO - Reading from file tmpfile_stdout 01:53:50 INFO - Output received: 01:53:50 INFO - Usage: mozinstall [options] installer 01:53:50 INFO - Options: 01:53:50 INFO - -h, --help show this help message and exit 01:53:50 INFO - -d DEST, --destination=DEST 01:53:50 INFO - Directory to install application into. [default: 01:53:50 INFO - "/builds/slave/test"] 01:53:50 INFO - --app=APP Application being installed. [default: firefox] 01:53:50 INFO - mkdir: /builds/slave/test/build/application 01:53:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:54:32 INFO - Reading from file tmpfile_stdout 01:54:32 INFO - Output received: 01:54:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:54:32 INFO - Running post-action listener: _resource_record_post_action 01:54:32 INFO - ##### 01:54:32 INFO - ##### Running run-tests step. 01:54:32 INFO - ##### 01:54:32 INFO - Running pre-action listener: _resource_record_pre_action 01:54:32 INFO - Running main action method: run_tests 01:54:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:54:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:54:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:54:32 INFO - 2015-11-02 01:54:32.139 screenresolution[1627:7583] starting screenresolution argv=screenresolution get 01:54:32 INFO - 2015-11-02 01:54:32.143 screenresolution[1627:7583] Display 0: 1600x1200x32@60 01:54:32 INFO - 2015-11-02 01:54:32.150 screenresolution[1628:7585] starting screenresolution argv=screenresolution list 01:54:32 INFO - Available Modes on Display 0 01:54:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:54:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:54:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:54:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:54:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:54:32 INFO - Intel HD Graphics 3000: 01:54:32 INFO - Chipset Model: Intel HD Graphics 3000 01:54:32 INFO - Type: GPU 01:54:32 INFO - Bus: Built-In 01:54:32 INFO - VRAM (Total): 512 MB 01:54:32 INFO - VRAM (Dynamic, Max): 10 01:54:32 INFO - Vendor: Intel (0x8086) 01:54:32 INFO - Device ID: 0x0116 01:54:32 INFO - Revision ID: 0x0009 01:54:32 INFO - Displays: 01:54:32 INFO - Display: 01:54:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:54:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:54:32 INFO - Display Serial Number: 1600x1200 60 01:54:32 INFO - Main Display: Yes 01:54:32 INFO - Mirror: Off 01:54:32 INFO - Online: Yes 01:54:32 INFO - Rotation: Supported 01:54:32 INFO - Return code: 0 01:54:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:54:32 INFO - Python 2.7.3 01:54:32 INFO - Return code: 0 01:54:32 INFO - grabbing minidump binary from tooltool 01:54:32 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')]} 01:54:32 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 0x108d259f0>, '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 0x108a84cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cef990>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:54:32 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 01:54:32 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 01:54:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:54:32 INFO - Return code: 0 01:54:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:54:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:54:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:54:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:54:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:54:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'chromez', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0073', '--webServer', 'localhost'] in /builds/slave/test/build 01:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite chromez --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r5-0073 --webServer localhost 01:54:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eLCXmH2BRG/Render', 01:54:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:54:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:54:32 INFO - 'HOME': '/Users/cltbld', 01:54:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:54:32 INFO - 'LOGNAME': 'cltbld', 01:54:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:54:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:32 INFO - 'MOZ_NO_REMOTE': '1', 01:54:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:54:32 INFO - 'NO_EM_RESTART': '1', 01:54:32 INFO - 'PAGER': '/bin/cat', 01:54:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:32 INFO - 'PWD': '/builds/slave/test', 01:54:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:54:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:54:32 INFO - 'SHELL': '/bin/bash', 01:54:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hn9MADnbEH/Listeners', 01:54:32 INFO - 'TMPDIR': '/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/', 01:54:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:54:32 INFO - 'USER': 'cltbld', 01:54:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:54:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:32 INFO - 'XPC_FLAGS': '0x0', 01:54:32 INFO - 'XPC_SERVICE_NAME': '0', 01:54:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 01:54:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'chromez', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0073', '--webServer', 'localhost'] with output_timeout 3600 01:54:32 INFO - mozversion INFO | application_buildid: 20151102000831 01:54:32 INFO - mozversion INFO | application_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:54:32 INFO - mozversion INFO | application_display_name: Nightly 01:54:32 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:54:32 INFO - mozversion INFO | application_name: Firefox 01:54:32 INFO - mozversion INFO | application_remotingname: firefox 01:54:32 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 01:54:32 INFO - mozversion INFO | application_vendor: Mozilla 01:54:32 INFO - mozversion INFO | application_version: 45.0a1 01:54:32 INFO - mozversion INFO | platform_buildid: 20151102000831 01:54:32 INFO - mozversion INFO | platform_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:54:32 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 01:54:32 INFO - mozversion INFO | platform_version: 45.0a1 01:54:32 INFO - 2015-11-02 01:54:32,885 DEBUG : using testdate: 1446458072 01:54:32 INFO - 2015-11-02 01:54:32,885 DEBUG : actual date: 1446458072 01:54:32 INFO - 2015-11-02 01:54:32,896 INFO : starting webserver on 'localhost:49275' 01:54:32 INFO - 2015-11-02 01:54:32,897 INFO : Starting test suite t-yosemite-r5-0073 01:54:32 INFO - 2015-11-02 01:54:32,897 INFO : Starting test tresize 01:54:32 INFO - 2015-11-02 01:54:32,897 DEBUG : operating with platform_type : mac_ 01:54:32 INFO - 2015-11-02 01:54:32,898 INFO : Initialising browser for tresize test... 01:54:32 INFO - 2015-11-02 01:54:32,936 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/getInfo.html 01:54:35 INFO - 2015-11-02 01:54:35,820 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:54:35 INFO - 2015-11-02 01:54:35,820 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:54:35 INFO - 2015-11-02 01:54:35,821 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 01:54:35 INFO - 2015-11-02 01:54:35,821 DEBUG : BROWSER_OUTPUT: __metrics 01:54:35 INFO - 2015-11-02 01:54:35,836 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:54:36 INFO - 2015-11-02 01:54:36,635 INFO : Browser initialized. 01:54:36 INFO - 2015-11-02 01:54:36,636 INFO : Running cycle 1/20 for tresize test... 01:54:36 INFO - 2015-11-02 01:54:36,636 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:54:54 INFO - 2015-11-02 01:54:54,881 DEBUG : BROWSER_OUTPUT: __start_report30.999716666666615__end_report 01:54:54 INFO - 2015-11-02 01:54:54,882 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458094876__endTimestamp 01:54:55 INFO - 2015-11-02 01:54:55,587 INFO : Browser exited with error code: 0 01:54:55 INFO - 2015-11-02 01:54:55,591 INFO : Running cycle 2/20 for tresize test... 01:54:55 INFO - 2015-11-02 01:54:55,591 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:55:13 INFO - 2015-11-02 01:55:13,111 DEBUG : BROWSER_OUTPUT: __start_report30.401283333333357__end_report 01:55:13 INFO - 2015-11-02 01:55:13,111 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458113110__endTimestamp 01:55:13 INFO - 2015-11-02 01:55:13,687 INFO : Browser exited with error code: 0 01:55:13 INFO - 2015-11-02 01:55:13,688 INFO : Running cycle 3/20 for tresize test... 01:55:13 INFO - 2015-11-02 01:55:13,688 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:55:31 INFO - 2015-11-02 01:55:31,165 DEBUG : BROWSER_OUTPUT: __start_report30.436566666666696__end_report 01:55:31 INFO - 2015-11-02 01:55:31,165 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458131161__endTimestamp 01:55:31 INFO - 2015-11-02 01:55:31,774 INFO : Browser exited with error code: 0 01:55:31 INFO - 2015-11-02 01:55:31,775 INFO : Running cycle 4/20 for tresize test... 01:55:31 INFO - 2015-11-02 01:55:31,775 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:55:49 INFO - 2015-11-02 01:55:49,347 DEBUG : BROWSER_OUTPUT: __start_report30.58266666666664__end_report 01:55:49 INFO - 2015-11-02 01:55:49,348 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458149345__endTimestamp 01:55:49 INFO - 2015-11-02 01:55:49,998 INFO : Browser exited with error code: 0 01:55:49 INFO - 2015-11-02 01:55:49,999 INFO : Running cycle 5/20 for tresize test... 01:55:50 INFO - 2015-11-02 01:55:49,999 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:56:07 INFO - 2015-11-02 01:56:07,686 DEBUG : BROWSER_OUTPUT: __start_report30.805083333333386__end_report 01:56:07 INFO - 2015-11-02 01:56:07,686 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458167679__endTimestamp 01:56:08 INFO - 2015-11-02 01:56:08,402 INFO : Browser exited with error code: 0 01:56:08 INFO - 2015-11-02 01:56:08,404 INFO : Running cycle 6/20 for tresize test... 01:56:08 INFO - 2015-11-02 01:56:08,404 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:56:26 INFO - 2015-11-02 01:56:26,871 DEBUG : BROWSER_OUTPUT: __start_report31.545183333333306__end_report 01:56:26 INFO - 2015-11-02 01:56:26,871 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458186870__endTimestamp 01:56:27 INFO - 2015-11-02 01:56:27,560 INFO : Browser exited with error code: 0 01:56:27 INFO - 2015-11-02 01:56:27,562 INFO : Running cycle 7/20 for tresize test... 01:56:27 INFO - 2015-11-02 01:56:27,562 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:56:45 INFO - 2015-11-02 01:56:45,715 DEBUG : BROWSER_OUTPUT: __start_report30.191750000000035__end_report 01:56:45 INFO - 2015-11-02 01:56:45,715 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458205713__endTimestamp 01:56:46 INFO - 2015-11-02 01:56:46,446 INFO : Browser exited with error code: 0 01:56:46 INFO - 2015-11-02 01:56:46,448 INFO : Running cycle 8/20 for tresize test... 01:56:46 INFO - 2015-11-02 01:56:46,448 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:57:04 INFO - 2015-11-02 01:57:04,819 DEBUG : BROWSER_OUTPUT: __start_report30.295516666666717__end_report 01:57:04 INFO - 2015-11-02 01:57:04,820 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458224813__endTimestamp 01:57:05 INFO - 2015-11-02 01:57:05,524 INFO : Browser exited with error code: 0 01:57:05 INFO - 2015-11-02 01:57:05,526 INFO : Running cycle 9/20 for tresize test... 01:57:05 INFO - 2015-11-02 01:57:05,526 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:57:24 INFO - 2015-11-02 01:57:24,119 DEBUG : BROWSER_OUTPUT: __start_report31.01871666666676__end_report 01:57:24 INFO - 2015-11-02 01:57:24,119 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458244117__endTimestamp 01:57:24 INFO - 2015-11-02 01:57:24,889 INFO : Browser exited with error code: 0 01:57:24 INFO - 2015-11-02 01:57:24,891 INFO : Running cycle 10/20 for tresize test... 01:57:24 INFO - 2015-11-02 01:57:24,891 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:57:43 INFO - 2015-11-02 01:57:43,405 DEBUG : BROWSER_OUTPUT: __start_report30.89261666666669__end_report 01:57:43 INFO - 2015-11-02 01:57:43,405 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458263399__endTimestamp 01:57:44 INFO - 2015-11-02 01:57:44,113 INFO : Browser exited with error code: 0 01:57:44 INFO - 2015-11-02 01:57:44,114 INFO : Running cycle 11/20 for tresize test... 01:57:44 INFO - 2015-11-02 01:57:44,114 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:58:02 INFO - 2015-11-02 01:58:02,468 DEBUG : BROWSER_OUTPUT: __start_report30.548883333333368__end_report 01:58:02 INFO - 2015-11-02 01:58:02,468 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458282465__endTimestamp 01:58:03 INFO - 2015-11-02 01:58:03,169 INFO : Browser exited with error code: 0 01:58:03 INFO - 2015-11-02 01:58:03,171 INFO : Running cycle 12/20 for tresize test... 01:58:03 INFO - 2015-11-02 01:58:03,171 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:58:21 INFO - 2015-11-02 01:58:21,785 DEBUG : BROWSER_OUTPUT: __start_report31.206949999999974__end_report 01:58:21 INFO - 2015-11-02 01:58:21,785 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458301782__endTimestamp 01:58:22 INFO - 2015-11-02 01:58:22,491 INFO : Browser exited with error code: 0 01:58:22 INFO - 2015-11-02 01:58:22,493 INFO : Running cycle 13/20 for tresize test... 01:58:22 INFO - 2015-11-02 01:58:22,493 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:58:40 INFO - 2015-11-02 01:58:40,951 DEBUG : BROWSER_OUTPUT: __start_report30.486866666666714__end_report 01:58:40 INFO - 2015-11-02 01:58:40,951 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458320950__endTimestamp 01:58:41 INFO - 2015-11-02 01:58:41,692 INFO : Browser exited with error code: 0 01:58:41 INFO - 2015-11-02 01:58:41,694 INFO : Running cycle 14/20 for tresize test... 01:58:41 INFO - 2015-11-02 01:58:41,694 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:59:00 INFO - 2015-11-02 01:59:00,224 DEBUG : BROWSER_OUTPUT: __start_report30.78128333333328__end_report 01:59:00 INFO - 2015-11-02 01:59:00,224 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458340217__endTimestamp 01:59:00 INFO - 2015-11-02 01:59:00,956 INFO : Browser exited with error code: 0 01:59:00 INFO - 2015-11-02 01:59:00,958 INFO : Running cycle 15/20 for tresize test... 01:59:00 INFO - 2015-11-02 01:59:00,958 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:59:19 INFO - 2015-11-02 01:59:19,339 DEBUG : BROWSER_OUTPUT: __start_report30.638083333333363__end_report 01:59:19 INFO - 2015-11-02 01:59:19,340 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458359333__endTimestamp 01:59:20 INFO - 2015-11-02 01:59:20,104 INFO : Browser exited with error code: 0 01:59:20 INFO - 2015-11-02 01:59:20,106 INFO : Running cycle 16/20 for tresize test... 01:59:20 INFO - 2015-11-02 01:59:20,106 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:59:38 INFO - 2015-11-02 01:59:38,440 DEBUG : BROWSER_OUTPUT: __start_report30.490133333333265__end_report 01:59:38 INFO - 2015-11-02 01:59:38,440 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458378434__endTimestamp 01:59:39 INFO - 2015-11-02 01:59:39,183 INFO : Browser exited with error code: 0 01:59:39 INFO - 2015-11-02 01:59:39,185 INFO : Running cycle 17/20 for tresize test... 01:59:39 INFO - 2015-11-02 01:59:39,186 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 01:59:57 INFO - 2015-11-02 01:59:57,775 DEBUG : BROWSER_OUTPUT: __start_report31.030949999999983__end_report 01:59:57 INFO - 2015-11-02 01:59:57,775 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458397770__endTimestamp 01:59:58 INFO - 2015-11-02 01:59:58,517 INFO : Browser exited with error code: 0 01:59:58 INFO - 2015-11-02 01:59:58,519 INFO : Running cycle 18/20 for tresize test... 01:59:58 INFO - 2015-11-02 01:59:58,519 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 02:00:17 INFO - 2015-11-02 02:00:17,177 DEBUG : BROWSER_OUTPUT: __start_report31.3209666666667__end_report 02:00:17 INFO - 2015-11-02 02:00:17,177 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458417176__endTimestamp 02:00:17 INFO - 2015-11-02 02:00:17,916 INFO : Browser exited with error code: 0 02:00:17 INFO - 2015-11-02 02:00:17,918 INFO : Running cycle 19/20 for tresize test... 02:00:17 INFO - 2015-11-02 02:00:17,918 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 02:00:36 INFO - 2015-11-02 02:00:36,139 DEBUG : BROWSER_OUTPUT: __start_report30.01019999999998__end_report 02:00:36 INFO - 2015-11-02 02:00:36,139 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458436137__endTimestamp 02:00:36 INFO - 2015-11-02 02:00:36,876 INFO : Browser exited with error code: 0 02:00:36 INFO - 2015-11-02 02:00:36,878 INFO : Running cycle 20/20 for tresize test... 02:00:36 INFO - 2015-11-02 02:00:36,878 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpnfZyh3/profile http://localhost:49275/startup_test/tresize/addon/content/tresize-test.html 02:00:55 INFO - 2015-11-02 02:00:55,278 DEBUG : BROWSER_OUTPUT: __start_report30.587499999999963__end_report 02:00:55 INFO - 2015-11-02 02:00:55,278 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458455276__endTimestamp 02:00:56 INFO - 2015-11-02 02:00:56,048 INFO : Browser exited with error code: 0 02:00:56 INFO - 2015-11-02 02:00:56,082 INFO : Completed test tresize (00:06:23) 02:00:56 INFO - 2015-11-02 02:00:56,083 INFO : Starting test tcanvasmark 02:00:56 INFO - 2015-11-02 02:00:56,083 DEBUG : operating with platform_type : mac_ 02:00:56 INFO - 2015-11-02 02:00:56,083 INFO : Initialising browser for tcanvasmark test... 02:00:56 INFO - 2015-11-02 02:00:56,101 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpDfy3Eg/profile http://localhost:49275/getInfo.html 02:00:58 INFO - 2015-11-02 02:00:58,528 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:00:58 INFO - 2015-11-02 02:00:58,529 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:00:58 INFO - 2015-11-02 02:00:58,529 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 02:00:58 INFO - 2015-11-02 02:00:58,529 DEBUG : BROWSER_OUTPUT: __metrics 02:00:58 INFO - 2015-11-02 02:00:58,545 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:00:59 INFO - 2015-11-02 02:00:59,336 INFO : Browser initialized. 02:00:59 INFO - 2015-11-02 02:00:59,337 INFO : Running cycle 1/1 for tcanvasmark test... 02:00:59 INFO - 2015-11-02 02:00:59,337 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/T/tmpDfy3Eg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/canvasmark/canvasmark.manifest.develop -tpchrome -tpnoisy -tpcycles 5 -tppagecycles 1 02:01:11 INFO - 2015-11-02 02:01:11,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 221626368 02:01:11 INFO - 2015-11-02 02:01:11,446 DEBUG : BROWSER_OUTPUT: 02:01:59 INFO - 2015-11-02 02:01:59,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49275/tests/canvasmark/index.html?auto=true (next: http://localhost:49275/tests/canvasmark/index.html?auto=true) 02:02:00 INFO - 2015-11-02 02:02:00,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 464560128 02:02:00 INFO - 2015-11-02 02:02:00,123 DEBUG : BROWSER_OUTPUT: 02:02:00 INFO - 2015-11-02 02:02:00,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 464572416 02:02:00 INFO - 2015-11-02 02:02:00,379 DEBUG : BROWSER_OUTPUT: 02:02:01 INFO - 2015-11-02 02:02:01,336 DEBUG : BROWSER_OUTPUT: 1446458521327 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] 02:02:48 INFO - 2015-11-02 02:02:48,848 DEBUG : BROWSER_OUTPUT: Cycle 2(1): loaded http://localhost:49275/tests/canvasmark/index.html?auto=true (next: http://localhost:49275/tests/canvasmark/index.html?auto=true) 02:02:49 INFO - 2015-11-02 02:02:49,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 534740992 02:02:49 INFO - 2015-11-02 02:02:49,116 DEBUG : BROWSER_OUTPUT: 02:02:49 INFO - 2015-11-02 02:02:49,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 534753280 02:02:49 INFO - 2015-11-02 02:02:49,365 DEBUG : BROWSER_OUTPUT: 02:03:37 INFO - 2015-11-02 02:03:37,766 DEBUG : BROWSER_OUTPUT: Cycle 3(1): loaded http://localhost:49275/tests/canvasmark/index.html?auto=true (next: http://localhost:49275/tests/canvasmark/index.html?auto=true) 02:03:38 INFO - 2015-11-02 02:03:38,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 525189120 02:03:38 INFO - 2015-11-02 02:03:38,035 DEBUG : BROWSER_OUTPUT: 02:03:38 INFO - 2015-11-02 02:03:38,286 DEBUG : BROWSER_OUTPUT: RSS: Main: 525197312 02:03:38 INFO - 2015-11-02 02:03:38,286 DEBUG : BROWSER_OUTPUT: 02:04:25 INFO - 2015-11-02 02:04:25,997 DEBUG : BROWSER_OUTPUT: Cycle 4(1): loaded http://localhost:49275/tests/canvasmark/index.html?auto=true (next: http://localhost:49275/tests/canvasmark/index.html?auto=true) 02:04:26 INFO - 2015-11-02 02:04:26,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 499888128 02:04:26 INFO - 2015-11-02 02:04:26,267 DEBUG : BROWSER_OUTPUT: 02:04:26 INFO - 2015-11-02 02:04:26,524 DEBUG : BROWSER_OUTPUT: RSS: Main: 500813824 02:04:26 INFO - 2015-11-02 02:04:26,525 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,001 DEBUG : BROWSER_OUTPUT: Cycle 5(1): loaded http://localhost:49275/tests/canvasmark/index.html?auto=true (next: http://localhost:49275/tests/canvasmark/index.html?auto=true) 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: RSS: Main: 493744128 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: |0;Asteroids - Bitmaps;333;307;302;298;315 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: |1;Asteroids - Vectors;543;572;568;570;597 02:05:15 INFO - 2015-11-02 02:05:15,274 DEBUG : BROWSER_OUTPUT: |2;Asteroids - Bitmaps- shapes- text;341;364;353;347;344 02:05:15 INFO - 2015-11-02 02:05:15,275 DEBUG : BROWSER_OUTPUT: |3;Asteroids - Shapes- shadows- blending;623;603;602;604;604 02:05:15 INFO - 2015-11-02 02:05:15,275 DEBUG : BROWSER_OUTPUT: |4;Arena5 - Vectors- shadows- bitmaps- text;237;239;238;227;240 02:05:15 INFO - 2015-11-02 02:05:15,275 DEBUG : BROWSER_OUTPUT: |5;Plasma - Maths- canvas shapes;871;860;878;909;874 02:05:15 INFO - 2015-11-02 02:05:15,275 DEBUG : BROWSER_OUTPUT: |6;3D Rendering - Maths- polygons- image transforms;374;414;396;350;408 02:05:15 INFO - 2015-11-02 02:05:15,276 DEBUG : BROWSER_OUTPUT: |7;Pixel blur - Math- getImageData- putImageData;1271;1247;1294;1200;1242 02:05:15 INFO - 2015-11-02 02:05:15,276 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:05:15 INFO - 2015-11-02 02:05:15,276 DEBUG : BROWSER_OUTPUT: __startTimestamp1446458715272__endTimestamp 02:05:15 INFO - 2015-11-02 02:05:15,276 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,276 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:05:15 INFO - 2015-11-02 02:05:15,277 DEBUG : BROWSER_OUTPUT: Number of tests: 8 02:05:15 INFO - 2015-11-02 02:05:15,277 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,278 DEBUG : BROWSER_OUTPUT: [#0] Asteroids - Bitmaps Cycles:5 Average:311.00 Median:307.00 stddev:13.84 (4.5%) stddev-sans-first:7.33 02:05:15 INFO - 2015-11-02 02:05:15,278 DEBUG : BROWSER_OUTPUT: Values: 333.0 307.0 302.0 298.0 315.0 02:05:15 INFO - 2015-11-02 02:05:15,278 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: [#1] Asteroids - Vectors Cycles:5 Average:570.00 Median:570.00 stddev:19.14 (3.4%) stddev-sans-first:13.60 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: Values: 543.0 572.0 568.0 570.0 597.0 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: [#2] Asteroids - Bitmaps- shapes- text Cycles:5 Average:349.80 Median:347.00 stddev:9.09 (2.6%) stddev-sans-first:8.83 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: Values: 341.0 364.0 353.0 347.0 344.0 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: [#3] Asteroids - Shapes- shadows- blending Cycles:5 Average:607.20 Median:604.00 stddev:8.87 (1.5%) stddev-sans-first:0.96 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: Values: 623.0 603.0 602.0 604.0 604.0 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: [#4] Arena5 - Vectors- shadows- bitmaps- text Cycles:5 Average:236.20 Median:238.00 stddev:5.26 (2.2%) stddev-sans-first:6.06 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: Values: 237.0 239.0 238.0 227.0 240.0 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,279 DEBUG : BROWSER_OUTPUT: [#5] Plasma - Maths- canvas shapes Cycles:5 Average:878.40 Median:874.00 stddev:18.37 (2.1%) stddev-sans-first:20.66 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: Values: 871.0 860.0 878.0 909.0 874.0 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: [#6] 3D Rendering - Maths- polygons- image transforms Cycles:5 Average:388.40 Median:396.00 stddev:26.36 (6.7%) stddev-sans-first:28.98 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: Values: 374.0 414.0 396.0 350.0 408.0 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: [#7] Pixel blur - Math- getImageData- putImageData Cycles:5 Average:1250.80 Median:1247.00 stddev:35.17 (2.8%) stddev-sans-first:38.46 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: Values: 1271.0 1247.0 1294.0 1200.0 1242.0 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:05:15 INFO - 2015-11-02 02:05:15,280 DEBUG : BROWSER_OUTPUT: 02:05:15 INFO - 2015-11-02 02:05:15,984 INFO : Browser exited with error code: 0 02:05:16 INFO - 2015-11-02 02:05:16,009 INFO : Completed test tcanvasmark (00:04:19) 02:05:16 INFO - 2015-11-02 02:05:16,360 INFO : Completed test suite (00:10:43) 02:05:16 INFO - 2015-11-02 02:05:16,360 DEBUG : Working with test: tresize 02:05:16 INFO - 2015-11-02 02:05:16,360 DEBUG : Generating results file: tresize 02:05:16 INFO - 2015-11-02 02:05:16,361 DEBUG : Working with test: tcanvasmark 02:05:16 INFO - 2015-11-02 02:05:16,361 DEBUG : Generating results file: tcanvasmark 02:05:16 INFO - 2015-11-02 02:05:16,361 INFO : CanvasMark benchmark 02:05:16 INFO - 2015-11-02 02:05:16,361 INFO : Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:05:16 INFO - 2015-11-02 02:05:16,525 DEBUG : process_Request line: tresize graph.html#tests=[[254,64,55]] 02:05:16 INFO - 2015-11-02 02:05:16,525 DEBUG : process_Request line: tresize 30.71 graph.html#tests=[[254,64,55]] 02:05:16 INFO - 2015-11-02 02:05:16,525 INFO : Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:05:16 INFO - 2015-11-02 02:05:16,652 DEBUG : process_Request line: tcanvasmark 4590.00 graph.html#tests=[[289,64,55]] 02:05:16 INFO - 2015-11-02 02:05:16,653 INFO : CanvasMark benchmark 02:05:16 INFO - 2015-11-02 02:05:16,653 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"tresize": [30.999716666666615, 30.401283333333357, 30.436566666666696, 30.58266666666664, 30.805083333333386, 31.545183333333306, 30.191750000000035, 30.295516666666717, 31.01871666666676, 30.89261666666669, 30.548883333333368, 31.206949999999974, 30.486866666666714, 30.78128333333328, 30.638083333333363, 30.490133333333265, 31.030949999999983, 31.3209666666667, 30.01019999999998, 30.587499999999963]}, "summary": {"suite": 30.638083333333363, "subtests": {"tresize": {"filtered": 30.638083333333363, "value": 30.638083333333363}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0073"}, "testrun": {"date": 1446458072, "suite": "tresize", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"Plasma - Maths- canvas shapes": [871.0, 860.0, 878.0, 909.0, 874.0], "Asteroids - Shapes- shadows- blending": [623.0, 603.0, 602.0, 604.0, 604.0], "Asteroids - Bitmaps- shapes- text": [341.0, 364.0, 353.0, 347.0, 344.0], "Arena5 - Vectors- shadows- bitmaps- text": [237.0, 239.0, 238.0, 227.0, 240.0], "Asteroids - Vectors": [543.0, 572.0, 568.0, 570.0, 597.0], "3D Rendering - Maths- polygons- image transforms": [374.0, 414.0, 396.0, 350.0, 408.0], "Pixel blur - Math- getImageData- putImageData": [1271.0, 1247.0, 1294.0, 1200.0, 1242.0], "Asteroids - Bitmaps": [333.0, 307.0, 302.0, 298.0, 315.0]}, "summary": {"suite": 4590.0, "subtests": {"Plasma - Maths- canvas shapes": {"filtered": 876.0, "value": 876.0}, "Asteroids - Shapes- shadows- blending": {"filtered": 603.5, "value": 603.5}, "Asteroids - Bitmaps- shapes- text": {"filtered": 350.0, "value": 350.0}, "Arena5 - Vectors- shadows- bitmaps- text": {"filtered": 238.5, "value": 238.5}, "Asteroids - Vectors": {"filtered": 571.0, "value": 571.0}, "3D Rendering - Maths- polygons- image transforms": {"filtered": 402.0, "value": 402.0}, "Pixel blur - Math- getImageData- putImageData": {"filtered": 1244.5, "value": 1244.5}, "Asteroids - Bitmaps": {"filtered": 304.5, "value": 304.5}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0073"}, "testrun": {"date": 1446458072, "suite": "tcanvasmark", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 5, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}] 02:05:16 INFO - RETURN: tresize: 30.71 02:05:16 INFO - RETURN: tcanvasmark: 4590.00 02:05:16 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tcanvasmark": {"url": "http://graphs.mozilla.org/graph.html#tests=[[289,64,55]]", "result": "4590.00"}, "tresize": {"url": "http://graphs.mozilla.org/graph.html#tests=[[254,64,55]]", "result": "30.71"}}} 02:05:16 INFO - Return code: 0 02:05:16 INFO - # TBPL SUCCESS # 02:05:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:05:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:05:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:05:16 INFO - 2015-11-02 02:05:16.710 screenresolution[1671:12173] starting screenresolution argv=screenresolution get 02:05:16 INFO - 2015-11-02 02:05:16.713 screenresolution[1671:12173] Display 0: 1600x1200x32@60 02:05:16 INFO - 2015-11-02 02:05:16.720 screenresolution[1672:12175] starting screenresolution argv=screenresolution list 02:05:16 INFO - Available Modes on Display 0 02:05:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:05:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:05:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:05:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:05:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:05:16 INFO - Intel HD Graphics 3000: 02:05:16 INFO - Chipset Model: Intel HD Graphics 3000 02:05:16 INFO - Type: GPU 02:05:16 INFO - Bus: Built-In 02:05:16 INFO - VRAM (Total): 512 MB 02:05:16 INFO - VRAM (Dynamic, Max): 10 02:05:16 INFO - Vendor: Intel (0x8086) 02:05:16 INFO - Device ID: 0x0116 02:05:16 INFO - Revision ID: 0x0009 02:05:16 INFO - Displays: 02:05:16 INFO - Display: 02:05:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:05:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:05:16 INFO - Display Serial Number: 1600x1200 60 02:05:16 INFO - Main Display: Yes 02:05:16 INFO - Mirror: Off 02:05:16 INFO - Online: Yes 02:05:16 INFO - Rotation: Supported 02:05:16 INFO - Return code: 0 02:05:16 INFO - Running post-action listener: _resource_record_post_action 02:05:16 INFO - Running post-run listener: _resource_record_post_run 02:05:18 INFO - Total resource usage - Wall time: 687s; CPU: 26.0%; Read bytes: 4706304; Write bytes: 472516608; Read time: 1165; Write time: 26400 02:05:18 INFO - install - Wall time: 43s; CPU: 30.0%; Read bytes: 336390656; Write bytes: 332630016; Read time: 36900; Write time: 3161 02:05:18 INFO - run-tests - Wall time: 645s; CPU: 26.0%; Read bytes: 3215360; Write bytes: 124524544; Read time: 718; Write time: 15985 02:05:18 INFO - Running post-run listener: _upload_blobber_files 02:05:18 INFO - Blob upload gear active. 02:05:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:05:18 INFO - Copying logs to upload dir... 02:05:18 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=748.292843 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 28 secs) (at 2015-11-02 02:05:18.089406) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 02:05:18.092693) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False build_url:https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012625 build_url: 'https://queue.taskcluster.net/v1/task/gObEFfXWStKFNoIdKMh1kQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 02:05:18.124837) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 02:05:18.125249) ========= 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.eLCXmH2BRG/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.hn9MADnbEH/Listeners TMPDIR=/var/folders/4n/n74n0twn4pqdpm97cjfm8rmc00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.006020 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 02:05:18.176551) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 02:05:18.176932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 02:05:18.177436) ========= ========= Total master_lag: 0.23 =========