builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0117 starttime: 1454764158.65 results: success (0) buildid: 20160206030207 builduid: 0783334e776e4232a1d172a3d2cf56ac revision: 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.651672) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.652037) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.652330) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005643 basedir: '/builds/slave/test' ========= master_lag: 0.08 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.738924) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.739233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.872304) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.872568) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005359 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.926556) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.926816) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.927152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:18.927434) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-02-06 05:09:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 25.1M=0s 2016-02-06 05:09:19 (25.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168318 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:19.474232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:19.474523) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:19.524064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:19.524364) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-02-06 05:09:19,604 truncating revision to first 12 chars 2016-02-06 05:09:19,604 Setting DEBUG logging. 2016-02-06 05:09:19,604 attempt 1/10 2016-02-06 05:09:19,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4295f9951e93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-06 05:09:19,856 unpacking tar archive at: mozilla-central-4295f9951e93/testing/mozharness/ program finished with exit code 0 elapsedTime=0.503069 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:20.231158) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:20.231513) ========= echo 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b program finished with exit code 0 elapsedTime=0.005100 script_repo_revision: '4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:20.286056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:20.286337) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:09:20.359802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-02-06 05:09:20.360086) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:09:20 INFO - MultiFileLogger online at 20160206 05:09:20 in /builds/slave/test 05:09:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:09:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:20 INFO - {'append_to_log': False, 05:09:20 INFO - 'base_work_dir': '/builds/slave/test', 05:09:20 INFO - 'blob_upload_branch': 'Firefox', 05:09:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:20 INFO - 'branch': 'Firefox', 05:09:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:20 INFO - 'config_files': ('talos/mac_config.py',), 05:09:20 INFO - 'default_actions': ('clobber', 05:09:20 INFO - 'read-buildbot-config', 05:09:20 INFO - 'download-and-extract', 05:09:20 INFO - 'populate-webroot', 05:09:20 INFO - 'create-virtualenv', 05:09:20 INFO - 'install', 05:09:20 INFO - 'run-tests'), 05:09:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:20 INFO - 'download_minidump_stackwalk': True, 05:09:20 INFO - 'download_symbols': 'ondemand', 05:09:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:20 INFO - 'installer_path': 'installer.exe', 05:09:20 INFO - 'log_level': 'info', 05:09:20 INFO - 'log_name': 'talos', 05:09:20 INFO - 'log_to_console': True, 05:09:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:09:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:09:20 INFO - 'opt_config_files': (), 05:09:20 INFO - 'pip_index': False, 05:09:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:20 INFO - 'cmd': ('bash', 05:09:20 INFO - '-c', 05:09:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:20 INFO - 'enabled': True, 05:09:20 INFO - 'halt_on_failure': False, 05:09:20 INFO - 'name': 'check_screen_resolution'},), 05:09:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:20 INFO - 'cmd': ('bash', 05:09:20 INFO - '-c', 05:09:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:20 INFO - 'enabled': True, 05:09:20 INFO - 'halt_on_failure': False, 05:09:20 INFO - 'name': 'check_screen_resolution'},), 05:09:20 INFO - 'run_cmd_checks_enabled': True, 05:09:20 INFO - 'sps_profile': False, 05:09:20 INFO - 'sps_profile_interval': 0, 05:09:20 INFO - 'suite': 'dromaeojs-e10s', 05:09:20 INFO - 'system_bits': '32', 05:09:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:20 INFO - 'title': 't-yosemite-r7-0117', 05:09:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:20 INFO - 'use_talos_json': True, 05:09:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:20 INFO - 'work_dir': 'build'} 05:09:20 INFO - ##### 05:09:20 INFO - ##### Running clobber step. 05:09:20 INFO - ##### 05:09:20 INFO - Running pre-action listener: _resource_record_pre_action 05:09:20 INFO - Running main action method: clobber 05:09:20 INFO - rmtree: /builds/slave/test/build 05:09:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:21 INFO - Running post-action listener: _resource_record_post_action 05:09:21 INFO - ##### 05:09:21 INFO - ##### Running read-buildbot-config step. 05:09:21 INFO - ##### 05:09:21 INFO - Running pre-action listener: _resource_record_pre_action 05:09:21 INFO - Running main action method: read_buildbot_config 05:09:21 INFO - Using buildbot properties: 05:09:21 INFO - { 05:09:21 INFO - "project": "", 05:09:21 INFO - "product": "firefox", 05:09:21 INFO - "script_repo_revision": "production", 05:09:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:09:21 INFO - "repository": "", 05:09:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:09:21 INFO - "buildid": "20160206030207", 05:09:21 INFO - "stage_platform": "macosx64", 05:09:21 INFO - "basedir": "/builds/slave/test", 05:09:21 INFO - "nightly_build": "True", 05:09:21 INFO - "buildnumber": 120, 05:09:21 INFO - "slavename": "t-yosemite-r7-0117", 05:09:21 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:09:21 INFO - "platform": "yosemite_r7", 05:09:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:09:21 INFO - "branch": "mozilla-central", 05:09:21 INFO - "repo_path": "mozilla-central", 05:09:21 INFO - "revision": "4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b", 05:09:21 INFO - "pgo_build": "False", 05:09:21 INFO - "builduid": "0783334e776e4232a1d172a3d2cf56ac", 05:09:21 INFO - "slavebuilddir": "test" 05:09:21 INFO - } 05:09:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 05:09:21 INFO - Running post-action listener: _resource_record_post_action 05:09:21 INFO - ##### 05:09:21 INFO - ##### Running download-and-extract step. 05:09:21 INFO - ##### 05:09:21 INFO - Running pre-action listener: _resource_record_pre_action 05:09:21 INFO - Running main action method: download_and_extract 05:09:21 INFO - mkdir: /builds/slave/test/build/tests 05:09: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')]} 05:09:21 INFO - https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:09:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/test_packages.json 05:09:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/test_packages.json 05:09:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:09:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:09:23 INFO - Downloaded 1277 bytes. 05:09:23 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:09:23 INFO - Using the following test package requirements: 05:09:23 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:09:23 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:09:23 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:09:23 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'jsshell-mac.zip'], 05:09:23 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:09:23 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:09:23 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:09:23 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:09:23 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:09:23 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:09:23 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:09:23 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:09:23 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:23 INFO - https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:09:24 INFO - Downloaded 17751816 bytes. 05:09:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:09:27 INFO - Return code: 0 05:09:27 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:09: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')]} 05:09:27 INFO - https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:09:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:09:32 INFO - Downloaded 17751816 bytes. 05:09:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:09:36 INFO - Return code: 0 05:09: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')]} 05:09:36 INFO - https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:09:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:09:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:09:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:09:37 INFO - Downloaded 10706547 bytes. 05:09:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:09:37 INFO - Return code: 0 05:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:37 INFO - https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:09:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:09:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:09:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:09:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 05:09:40 INFO - Downloaded 111934331 bytes. 05:09:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:09:40 INFO - mkdir: /builds/slave/test/properties 05:09:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:40 INFO - Contents: 05:09:40 INFO - build_url:https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:09:41 INFO - Running post-action listener: _resource_record_post_action 05:09:41 INFO - Running post-action listener: set_extra_try_arguments 05:09:41 INFO - ##### 05:09:41 INFO - ##### Running populate-webroot step. 05:09:41 INFO - ##### 05:09:41 INFO - Running pre-action listener: _resource_record_pre_action 05:09:41 INFO - Running main action method: populate_webroot 05:09:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:41 INFO - '--tppagecycles', 05:09:41 INFO - '7'], 05:09:41 INFO - 'tests': ['tsvgm']}}, 05:09:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:09:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:09:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'tests': ['damp', 'tps']}, 05:09:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['damp', 'tps']}, 05:09:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:09:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:41 INFO - 'other': {'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'other_l64': {'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'other_nol64': {'tests': ['a11yr', 05:09:41 INFO - 'ts_paint', 05:09:41 INFO - 'tpaint', 05:09:41 INFO - 'sessionrestore', 05:09:41 INFO - 'sessionrestore_no_auto_restore']}, 05:09:41 INFO - 'svgr': {'tests': ['tsvgx', 05:09:41 INFO - 'tsvgr_opacity', 05:09:41 INFO - 'tart', 05:09:41 INFO - 'tscrollx', 05:09:41 INFO - 'cart']}, 05:09:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['tsvgx', 05:09:41 INFO - 'tsvgr_opacity', 05:09:41 INFO - 'tart', 05:09:41 INFO - 'tscrollx', 05:09:41 INFO - 'cart']}, 05:09:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'tests': ['tp5o']}, 05:09:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'talos_options': ['--e10s'], 05:09:41 INFO - 'tests': ['tp5o']}, 05:09:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'talos_options': ['--xperf_path', 05:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:41 INFO - 'tests': ['tp5n']}, 05:09:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:41 INFO - 'talos_options': ['--e10s', 05:09:41 INFO - '--xperf_path', 05:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:41 INFO - 'tests': ['tp5n']}}, 05:09:41 INFO - 'talos.zip': {'path': '', 05:09:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:41 INFO - Running post-action listener: _resource_record_post_action 05:09:41 INFO - ##### 05:09:41 INFO - ##### Running create-virtualenv step. 05:09:41 INFO - ##### 05:09:41 INFO - Running pre-action listener: _resource_record_pre_action 05:09:41 INFO - Running main action method: create_virtualenv 05:09:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:41 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 05:09:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:41 INFO - Using real prefix '/tools/python27' 05:09:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:45 INFO - Installing pip.................done. 05:09:45 INFO - Return code: 0 05:09:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:45 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')]} 05:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:45 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')]} 05:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:45 INFO - 'HOME': '/Users/cltbld', 05:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:45 INFO - 'LOGNAME': 'cltbld', 05:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:45 INFO - 'MOZ_NO_REMOTE': '1', 05:09:45 INFO - 'NO_EM_RESTART': '1', 05:09:45 INFO - 'PAGER': '/bin/cat', 05:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:45 INFO - 'PWD': '/builds/slave/test', 05:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:45 INFO - 'SHELL': '/bin/bash', 05:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:09:45 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:45 INFO - 'USER': 'cltbld', 05:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:45 INFO - 'XPC_FLAGS': '0x0', 05:09:45 INFO - 'XPC_SERVICE_NAME': '0', 05:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:45 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 05:09:45 INFO - Cleaning up... 05:09:45 INFO - Return code: 0 05:09:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:09:45 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')]} 05:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:45 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')]} 05:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:45 INFO - 'HOME': '/Users/cltbld', 05:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:45 INFO - 'LOGNAME': 'cltbld', 05:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:45 INFO - 'MOZ_NO_REMOTE': '1', 05:09:45 INFO - 'NO_EM_RESTART': '1', 05:09:45 INFO - 'PAGER': '/bin/cat', 05:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:45 INFO - 'PWD': '/builds/slave/test', 05:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:45 INFO - 'SHELL': '/bin/bash', 05:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:09:45 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:45 INFO - 'USER': 'cltbld', 05:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:45 INFO - 'XPC_FLAGS': '0x0', 05:09:45 INFO - 'XPC_SERVICE_NAME': '0', 05:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:45 INFO - Downloading/unpacking psutil>=0.7.1 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:48 INFO - Installing collected packages: psutil 05:09:48 INFO - Running setup.py install for psutil 05:09:48 INFO - building 'psutil._psutil_osx' extension 05:09:48 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 05:09:48 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 05:09:48 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 05:09:49 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 05:09:49 INFO - building 'psutil._psutil_posix' extension 05:09:49 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 05:09:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:09:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:09:49 INFO - ^ 05:09:49 INFO - 1 warning generated. 05:09:49 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 05:09:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:49 INFO - Successfully installed psutil 05:09:49 INFO - Cleaning up... 05:09:49 INFO - Return code: 0 05:09:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:49 INFO - 'HOME': '/Users/cltbld', 05:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:49 INFO - 'LOGNAME': 'cltbld', 05:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:49 INFO - 'MOZ_NO_REMOTE': '1', 05:09:49 INFO - 'NO_EM_RESTART': '1', 05:09:49 INFO - 'PAGER': '/bin/cat', 05:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:49 INFO - 'PWD': '/builds/slave/test', 05:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:49 INFO - 'SHELL': '/bin/bash', 05:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:09:49 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:49 INFO - 'USER': 'cltbld', 05:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:49 INFO - 'XPC_FLAGS': '0x0', 05:09:49 INFO - 'XPC_SERVICE_NAME': '0', 05:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:52 INFO - Installing collected packages: mozsystemmonitor 05:09:52 INFO - Running setup.py install for mozsystemmonitor 05:09:52 INFO - Successfully installed mozsystemmonitor 05:09:52 INFO - Cleaning up... 05:09:52 INFO - Return code: 0 05:09:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:52 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')]} 05:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:52 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')]} 05:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:52 INFO - 'HOME': '/Users/cltbld', 05:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:52 INFO - 'LOGNAME': 'cltbld', 05:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:52 INFO - 'MOZ_NO_REMOTE': '1', 05:09:52 INFO - 'NO_EM_RESTART': '1', 05:09:52 INFO - 'PAGER': '/bin/cat', 05:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:52 INFO - 'PWD': '/builds/slave/test', 05:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:52 INFO - 'SHELL': '/bin/bash', 05:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:09:52 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:52 INFO - 'USER': 'cltbld', 05:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:52 INFO - 'XPC_FLAGS': '0x0', 05:09:52 INFO - 'XPC_SERVICE_NAME': '0', 05:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:52 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 05:09:52 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 05:09:52 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 05:09:52 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 05:09:52 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 05:09:52 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 05:09:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:54 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 05:09:54 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 05:09:54 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 05:09:54 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 05:09:54 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 05:09:54 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 05:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:55 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 05:09:55 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 05:09:55 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 05:09:55 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 05:09:55 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 05:09:55 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 05:09:55 INFO - Downloading docopt-0.6.1.tar.gz 05:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:56 INFO - Running setup.py install for blobuploader 05:09:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:56 INFO - Running setup.py install for requests 05:09:56 INFO - Running setup.py install for docopt 05:09:56 INFO - Successfully installed blobuploader requests docopt 05:09:56 INFO - Cleaning up... 05:09:56 INFO - Return code: 0 05:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:56 INFO - 'HOME': '/Users/cltbld', 05:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:56 INFO - 'LOGNAME': 'cltbld', 05:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:56 INFO - 'MOZ_NO_REMOTE': '1', 05:09:56 INFO - 'NO_EM_RESTART': '1', 05:09:56 INFO - 'PAGER': '/bin/cat', 05:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:56 INFO - 'PWD': '/builds/slave/test', 05:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:56 INFO - 'SHELL': '/bin/bash', 05:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:09:56 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:56 INFO - 'USER': 'cltbld', 05:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:56 INFO - 'XPC_FLAGS': '0x0', 05:09:56 INFO - 'XPC_SERVICE_NAME': '0', 05:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-SXzN8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-wmPpW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-1sEiWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-TEJOFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-WoUxg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-hfAvaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Wjcgvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-_oo8cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-0bApdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-VxagDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-pZTU6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-J4U8QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-RSguI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-sHnzM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-SaOZa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fDu0Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:58 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-O6ud4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:58 INFO - Running setup.py install for manifestparser 05:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:59 INFO - Running setup.py install for mozcrash 05:09:59 INFO - Running setup.py install for mozdebug 05:09:59 INFO - Running setup.py install for mozdevice 05:09:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:59 INFO - Running setup.py install for mozfile 05:09:59 INFO - Running setup.py install for mozhttpd 05:09:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:59 INFO - Running setup.py install for mozinfo 05:09:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Running setup.py install for mozInstall 05:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Running setup.py install for mozleak 05:10:00 INFO - Running setup.py install for mozlog 05:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Running setup.py install for moznetwork 05:10:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Running setup.py install for mozprocess 05:10:00 INFO - Running setup.py install for mozprofile 05:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:00 INFO - Running setup.py install for mozrunner 05:10:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:01 INFO - Running setup.py install for mozscreenshot 05:10:01 INFO - Running setup.py install for moztest 05:10:01 INFO - Running setup.py install for mozversion 05:10:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:01 INFO - Cleaning up... 05:10:01 INFO - Return code: 0 05:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:01 INFO - 'HOME': '/Users/cltbld', 05:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:01 INFO - 'LOGNAME': 'cltbld', 05:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:01 INFO - 'MOZ_NO_REMOTE': '1', 05:10:01 INFO - 'NO_EM_RESTART': '1', 05:10:01 INFO - 'PAGER': '/bin/cat', 05:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:01 INFO - 'PWD': '/builds/slave/test', 05:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:01 INFO - 'SHELL': '/bin/bash', 05:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:10:01 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:01 INFO - 'USER': 'cltbld', 05:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:01 INFO - 'XPC_FLAGS': '0x0', 05:10:01 INFO - 'XPC_SERVICE_NAME': '0', 05:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-evcQOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:01 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)) 05:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FqHZZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-7qaPFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ur4UUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-cc0sT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-7YLuqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-yU36Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-4oIlfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-sRhWRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-HlR5D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-PXS7aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:02 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)) 05:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-VaKAiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:03 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)) 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8GOj1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-hRU3pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:03 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)) 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-VcmJyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:03 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)) 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3GjW4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:03 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)) 05:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-4XnX85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:03 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)) 05:10:03 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)) 05:10:03 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)) 05:10:03 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)) 05:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:05 INFO - Downloading blessings-1.6.tar.gz 05:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:10:06 INFO - Installing collected packages: blessings 05:10:06 INFO - Running setup.py install for blessings 05:10:06 INFO - Successfully installed blessings 05:10:06 INFO - Cleaning up... 05:10:06 INFO - Return code: 0 05:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:06 INFO - Reading from file tmpfile_stdout 05:10:06 INFO - Current package versions: 05:10:06 INFO - blessings == 1.6 05:10:06 INFO - blobuploader == 1.2.4 05:10:06 INFO - docopt == 0.6.1 05:10:06 INFO - manifestparser == 1.1 05:10:06 INFO - mozInstall == 1.12 05:10:06 INFO - mozcrash == 0.16 05:10:06 INFO - mozdebug == 0.1 05:10:06 INFO - mozdevice == 0.48 05:10:06 INFO - mozfile == 1.2 05:10:06 INFO - mozhttpd == 0.7 05:10:06 INFO - mozinfo == 0.9 05:10:06 INFO - mozleak == 0.1 05:10:06 INFO - mozlog == 3.1 05:10:06 INFO - moznetwork == 0.27 05:10:06 INFO - mozprocess == 0.22 05:10:06 INFO - mozprofile == 0.28 05:10:06 INFO - mozrunner == 6.11 05:10:06 INFO - mozscreenshot == 0.1 05:10:06 INFO - mozsystemmonitor == 0.0 05:10:06 INFO - moztest == 0.7 05:10:06 INFO - mozversion == 1.4 05:10:06 INFO - psutil == 3.1.1 05:10:06 INFO - requests == 1.2.3 05:10:06 INFO - wsgiref == 0.1.2 05:10:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:06 INFO - 'HOME': '/Users/cltbld', 05:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:06 INFO - 'LOGNAME': 'cltbld', 05:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:06 INFO - 'MOZ_NO_REMOTE': '1', 05:10:06 INFO - 'NO_EM_RESTART': '1', 05:10:06 INFO - 'PAGER': '/bin/cat', 05:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:06 INFO - 'PWD': '/builds/slave/test', 05:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:06 INFO - 'SHELL': '/bin/bash', 05:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:10:06 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:06 INFO - 'USER': 'cltbld', 05:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:06 INFO - 'XPC_FLAGS': '0x0', 05:10:06 INFO - 'XPC_SERVICE_NAME': '0', 05:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:10:06 INFO - Cleaning up... 05:10:06 INFO - Return code: 0 05:10:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:06 INFO - 'HOME': '/Users/cltbld', 05:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:06 INFO - 'LOGNAME': 'cltbld', 05:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:06 INFO - 'MOZ_NO_REMOTE': '1', 05:10:06 INFO - 'NO_EM_RESTART': '1', 05:10:06 INFO - 'PAGER': '/bin/cat', 05:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:06 INFO - 'PWD': '/builds/slave/test', 05:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:06 INFO - 'SHELL': '/bin/bash', 05:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:10:06 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:06 INFO - 'USER': 'cltbld', 05:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:06 INFO - 'XPC_FLAGS': '0x0', 05:10:06 INFO - 'XPC_SERVICE_NAME': '0', 05:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:07 INFO - Downloading/unpacking jsonschema 05:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:09 INFO - Downloading/unpacking functools32 (from jsonschema) 05:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:09 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:10 INFO - warning: no files found matching '*.txt' 05:10:10 INFO - no previously-included directories found matching 'build' 05:10:10 INFO - no previously-included directories found matching 'dist' 05:10:10 INFO - no previously-included directories found matching '.git*' 05:10:10 INFO - Installing collected packages: jsonschema, functools32 05:10:10 INFO - Running setup.py install for functools32 05:10:10 INFO - warning: no files found matching '*.txt' 05:10:10 INFO - no previously-included directories found matching 'build' 05:10:10 INFO - no previously-included directories found matching 'dist' 05:10:10 INFO - no previously-included directories found matching '.git*' 05:10:10 INFO - Successfully installed jsonschema functools32 05:10:10 INFO - Cleaning up... 05:10:10 INFO - Return code: 0 05:10:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101166a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10116d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95b57ab40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/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.zacNHzEg1g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:10 INFO - 'HOME': '/Users/cltbld', 05:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:10 INFO - 'LOGNAME': 'cltbld', 05:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:10 INFO - 'MOZ_NO_REMOTE': '1', 05:10:10 INFO - 'NO_EM_RESTART': '1', 05:10:10 INFO - 'PAGER': '/bin/cat', 05:10:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:10 INFO - 'PWD': '/builds/slave/test', 05:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:10 INFO - 'SHELL': '/bin/bash', 05:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:10:10 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:10 INFO - 'USER': 'cltbld', 05:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:10 INFO - 'XPC_FLAGS': '0x0', 05:10:10 INFO - 'XPC_SERVICE_NAME': '0', 05:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:10 INFO - Downloading/unpacking flake8 05:10:10 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 05:10:10 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 05:10:10 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 05:10:10 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 05:10:10 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 05:10:10 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 05:10:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:10:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:10:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 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 05:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:10:14 INFO - no previously-included directories found matching 'docs/_build' 05:10:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:10:14 INFO - Running setup.py install for pep8 05:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:10:14 INFO - no previously-included directories found matching 'docs/_build' 05:10:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:10:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:10:14 INFO - Cleaning up... 05:10:14 INFO - Return code: 0 05:10:14 INFO - Running post-action listener: _resource_record_post_action 05:10:14 INFO - Running post-action listener: _start_resource_monitoring 05:10:14 INFO - Starting resource monitoring. 05:10:14 INFO - ##### 05:10:14 INFO - ##### Running install step. 05:10:14 INFO - ##### 05:10:14 INFO - Running pre-action listener: _resource_record_pre_action 05:10:14 INFO - Running main action method: install 05:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:14 INFO - Reading from file tmpfile_stdout 05:10:14 INFO - Detecting whether we're running mozinstall >=1.0... 05:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:10:14 INFO - Reading from file tmpfile_stdout 05:10:14 INFO - Output received: 05:10:14 INFO - Usage: mozinstall [options] installer 05:10:14 INFO - Options: 05:10:14 INFO - -h, --help show this help message and exit 05:10:14 INFO - -d DEST, --destination=DEST 05:10:14 INFO - Directory to install application into. [default: 05:10:14 INFO - "/builds/slave/test"] 05:10:14 INFO - --app=APP Application being installed. [default: firefox] 05:10:14 INFO - mkdir: /builds/slave/test/build/application 05:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:10:48 INFO - Reading from file tmpfile_stdout 05:10:48 INFO - Output received: 05:10:48 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:10:48 INFO - Running post-action listener: _resource_record_post_action 05:10:48 INFO - ##### 05:10:48 INFO - ##### Running run-tests step. 05:10:48 INFO - ##### 05:10:48 INFO - Running pre-action listener: _resource_record_pre_action 05:10:48 INFO - Running main action method: run_tests 05:10:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:48 INFO - 2016-02-06 05:10:48.963 screenresolution[2705:30816] starting screenresolution argv=screenresolution get 05:10:48 INFO - 2016-02-06 05:10:48.966 screenresolution[2705:30816] Display 0: 1600x1200x32@60 05:10:48 INFO - 2016-02-06 05:10:48.972 screenresolution[2706:30818] starting screenresolution argv=screenresolution list 05:10:48 INFO - Available Modes on Display 0 05:10:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:49 INFO - Intel Iris: 05:10:49 INFO - Chipset Model: Intel Iris 05:10:49 INFO - Type: GPU 05:10:49 INFO - Bus: Built-In 05:10:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:49 INFO - Vendor: Intel (0x8086) 05:10:49 INFO - Device ID: 0x0a2e 05:10:49 INFO - Revision ID: 0x0009 05:10:49 INFO - Displays: 05:10:49 INFO - Display: 05:10:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:49 INFO - Display Serial Number: 1600x1200 60 05:10:49 INFO - Main Display: Yes 05:10:49 INFO - Mirror: Off 05:10:49 INFO - Online: Yes 05:10:49 INFO - Rotation: Supported 05:10:49 INFO - Return code: 0 05:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:49 INFO - Python 2.7.3 05:10:49 INFO - Return code: 0 05:10:49 INFO - grabbing minidump binary from tooltool 05:10: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')]} 05:10:49 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 0x10100bd30>, '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 0x100e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101001b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:49 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 05:10:49 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 05:10:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:49 INFO - Return code: 0 05:10:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:10:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:10:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:10:50 INFO - Return code: 0 05:10:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kSy2G5Ay6M/Render', 05:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:50 INFO - 'HOME': '/Users/cltbld', 05:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:50 INFO - 'LOGNAME': 'cltbld', 05:10:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:50 INFO - 'MOZ_NO_REMOTE': '1', 05:10:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:50 INFO - 'NO_EM_RESTART': '1', 05:10:50 INFO - 'PAGER': '/bin/cat', 05:10:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:50 INFO - 'PWD': '/builds/slave/test', 05:10:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:50 INFO - 'SHELL': '/bin/bash', 05:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zacNHzEg1g/Listeners', 05:10:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:50 INFO - 'USER': 'cltbld', 05:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:50 INFO - 'XPC_FLAGS': '0x0', 05:10:50 INFO - 'XPC_SERVICE_NAME': '0', 05:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:10:50 INFO - mozversion application_buildid: 20160206030207 05:10:50 INFO - mozversion application_changeset: 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b 05:10:50 INFO - mozversion application_display_name: Nightly 05:10:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:50 INFO - mozversion application_name: Firefox 05:10:50 INFO - mozversion application_remotingname: firefox 05:10:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:10:50 INFO - mozversion application_vendor: Mozilla 05:10:50 INFO - mozversion application_version: 47.0a1 05:10:50 INFO - mozversion platform_buildid: 20160206030207 05:10:50 INFO - mozversion platform_changeset: 4295f9951e9331a2f2bd0a8ea8c4ad73af141a7b 05:10:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:10:50 INFO - mozversion platform_version: 47.0a1 05:10:50 INFO - using testdate: 1454764250 05:10:50 INFO - actual date: 1454764250 05:10:50 INFO - starting webserver on 'localhost:52949' 05:10:50 INFO - SUITE-START | Running 2 tests 05:10:50 INFO - TEST-START | dromaeo_css 05:10:50 INFO - operating with platform_type : mac_ 05:10:50 INFO - Initialising browser for dromaeo_css test... 05:10:50 INFO - TEST-INFO | started process 2724 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp2LKtcL/profile http://localhost:52949/getInfo.html) 05:10:54 INFO - PROCESS | 2724 | __metrics Screen width/height:1600/1200 05:10:54 INFO - PROCESS | 2724 | colorDepth:24 05:10:54 INFO - PROCESS | 2724 | Browser inner width/height: 1024/689 05:10:54 INFO - PROCESS | 2724 | __metrics 05:10:54 INFO - TEST-INFO | 2724: exit 0 05:10:54 INFO - Browser initialized. 05:10:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:54 INFO - TEST-INFO | started process 2729 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp2LKtcL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:07 INFO - PROCESS | 2729 | RSS: Main: 258031616 05:11:07 INFO - PROCESS | 2729 | 05:11:56 INFO - PROCESS | 2729 | 1454764316735 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] 05:14:06 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52949/tests/dromaeo/cssquery-ext.html) 05:14:07 INFO - PROCESS | 2729 | RSS: Main: 271478784 05:14:07 INFO - PROCESS | 2729 | 05:17:09 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-ext.html (next: http://localhost:52949/tests/dromaeo/cssquery-jquery.html) 05:17:10 INFO - PROCESS | 2729 | RSS: Main: 283078656 05:17:10 INFO - PROCESS | 2729 | 05:20:12 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52949/tests/dromaeo/cssquery-mootools.html) 05:20:12 INFO - PROCESS | 2729 | RSS: Main: 284950528 05:20:12 INFO - PROCESS | 2729 | 05:23:14 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52949/tests/dromaeo/cssquery-prototype.html) 05:23:15 INFO - PROCESS | 2729 | RSS: Main: 288018432 05:23:15 INFO - PROCESS | 2729 | 05:26:17 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52949/tests/dromaeo/cssquery-yui.html) 05:26:18 INFO - PROCESS | 2729 | RSS: Main: 290000896 05:26:18 INFO - PROCESS | 2729 | 05:29:20 INFO - PROCESS | 2729 | Cycle 1(1): loaded http://localhost:52949/tests/dromaeo/cssquery-yui.html (next: http://localhost:52949/tests/dromaeo/cssquery-dojo.html) 05:29:21 INFO - PROCESS | 2729 | RSS: Main: 292032512 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | __start_tp_report 05:29:21 INFO - PROCESS | 2729 | _x_x_mozilla_page_load 05:29:21 INFO - PROCESS | 2729 | _x_x_mozilla_page_load_details 05:29:21 INFO - PROCESS | 2729 | |i|pagename|runs| 05:29:21 INFO - PROCESS | 2729 | |0;dojo.html;2829.8160619559726;3449.465332873404;3463.2207753255507;3482.2339085401204;3505.964940350596;5603.719814009293;5627;5689.402612725667;5689.8577535561635;5764.308283006035;5552.805651802188;5578.219049333086;5711;5830.825075247739;5895.027320492124;5011.448740638536;5043.546080852722;5060.569851562622;5062.746862656871;5093.465186155456;786.0839118830833;5039.697618142924;5040;5047;5162.096633089213;5110.872228194306;5137.229415587654;5137.948620513803;5183.248428977815;5189.195674670432;4342.782860856944;5192.3769147702515;5196.402413722432;5216.504432078947;5243.659162154486;4358.629516491102;5071.644984851062;5102.336696229503;5125.436202017775;5200.245964335188;5449.782008719647;5563.582731295169;5568.52667523261;5611.635243709186;5669.518090962273;6015.0676645120075;6193.504519638418;6205.689715514206;6209.130721698965;6211.627302361828;6198.690065496752;6234.781782637586;6243.469305109026;6249.468795152417;6269.780557680459;5851.180834683148;5851.941480585225;5854.941450585482;5857.619254748428;5904.822855314347;6192.442680158717;6253.437190652863;6340.968295158587;6354.364563543609;6355.713992870332;5048.873778155576;5930.140129681172;5972.402759723993;6009;6053.939460605337;6105.603135796247;6110.175126357974;6123.969380153071;6128.78549250783;6151.876962460726;6140.508759299335;6153.907691384633;6166.982447896136;6180.196574445294;6200.441960223601;6100.145979562864;6127.662978536134;6181.196444462281;6191.164192834;6213.5339849511465;4775.856724298276;4913.361263035854;4915.680480768739;4916.459189489154;4971.751412429364;4403.911921761547;4413.7793110345;4414.80133393998;4416.779161041935;4468.731876087445;5811.360750317462;5827.155062515912;5867.882642347215;5895.14520394549;5901.616394934315;6122.459144589048;6132.478739307119;6197.25632924043;6201.534884883652;6218.098375735493;5847.678377689268;5851;5854.4730974212525;5854.4730974212525;5873.970630146822;5266.2890509781455;5341.118715411913;5359.276497672842;5385.111456609616;5391.433899440581;5665.575081868877;6286.805506953664;6397.552171347961;6443.871122577522;6464.903026454513;6211.409916057967;6219.0982307566055;6230.470410015018;6232.8130156095385;6246.531510136849;6189.381061893775;6284.811455656338;6344.333844946399;6387.712552935222;6413.679316034273;6036.607620504828;6053.909191362045;6056;6063.787767428119;6066;5108.821191258288;6108.706347746941;6131.162586540493;6161.753529858755;6174.166487524127;6082.391760823883;6166.969165154145;6173.259208894961;6190.535709821871;6191.504679625709;6078.02751559766;6099.207103076749;6136.20229370179;6142.877142457215;6173.56785025044;5857.033589457692;5993.3706960768495;6024.216851809412;6035.034394496859;6083.178770866053;6000.039993601003;6098.054801505774;6126.5098792097415;6129.969350153221;6137.570370074052;6000.969995149996;6017.849553761191;6026.186464827368;6029.939700602938;6032.577719559588 05:29:21 INFO - PROCESS | 2729 | |1;ext.html;463.90981194193625;472.4142063840836;551.396221137854;569.82050654044;601.5398220361421;36836.26327473449;36966.8151659242;37187.62812371876;37280.62719372809;37339.81330093353;37691;38193.23613527728;38208;38308;38363.80818095906;9431.245500359972;9623.807523849518;9704.805903881917;9710.36882602632;9713.805723885518;9408.576614052385;9596.664116755916;9614.56734446948;9623.95188024059;9680.031996800333;9597.136257736802;9626.614935402575;9662.08210220028;9664.661736839213;9689.951550242275;9536.046395360478;9562.808743825153;9586.08932151448;9593.90406095937;9611.134997850191;9447.90552094477;9603.90396096044;9621.807563848753;9627.422354658673;9630.662926797599;37521.24957500862;37694.81152594247;37807;37879;37887.43168852442;2688.01887311132;2790.693023767384;3322.2524931890375;3808.9428658570146;4058.127502586958;25744;26342;26426;26559;26738.866305668347;2762.4475104979083;2816.5915942188267;2866.4410439964217;2885.1056172586564;2973.0337640266785;321760;322395;323968;324049;324353;49288.75355623199;59324.7033764837;59634.40365596375;59914.70042649759;60086.69956650189;61429.69285153546;62300.37699622946;63050.68474657597;63176.68411658004;63465;13459.394327255297;14428.85571144296;16541.917290413712;16600.08699521538;16632.584185395463;12567.748645027232;12626.494940202288;12662.683432914251;12668.746625067632;12723.809142862865;18750.0924196195;19356;19457.610847782966;19457.8054219456;19478.513037174183;6914.896276555855;8288.336933045342;8350.4154709171;8575.442596231349;8576.914230857612;951.1059603972244;988.3773222869546;990.6384169777979;1086.4079076903106;1135.500379832871;27549.724502754714;29265.853670731936;29283;29417.11748647544;29922.700772992426;1062.1078294232727;1096.221682605359;1162.4187906046977;1170.5142365918218;1190.2739329009298;13750.587482375544;14081.436742530184;14162.414477267914;14302.499412520512;14398.640033999234;508900;511753.4412327989;512292;512988;516209;53723.6569085776;54361;54541.90916181733;54600.72699636476;54961.90076198534;1388.7986241994854;1404.206623257856;1439.5105664074056;1449.528728340735;1455.9636009099645;25784.226473205836;26949.377626258403;27215.72784272132;27415.725842741318;27498.862505687342;47158.76420618012;47308;47604.28593571168;47635.047299053134;47831;449647;451734;452058;452881;454220;29378.55932161062;29655.703442965296;29666.258343541584;30022;31323.84338078295;39527;40249.19501609893;41005.58994410137;41182.97042573959;41348.793256033525;33838.154046149044;34749.47875781916;35213.767518136745;35795.463068053476;35838.820805895804;34976.65023349734;35327.76352827639;35483.467747984316;35765.46351804673;35791.105222369646;31005.689943101184;34321.48517772186;35343.82328088343;35348.64651353556;35646;30790.53814192744;30951.690483095783;32068.83965580157;33650.49524257187;33956.49065263974;21727.891360543097;24087.51824963526;24389.51220975535;24818.751812482365;24954.87522562448 05:29:21 INFO - PROCESS | 2729 | |2;jquery.html;316.5015101215585;359.714027348258;360.1626219040729;363.6726945748827;367.1042655919554;15824.84175158248;16959.915200423995;18572;18772.624547509076;19413.611727765452;19517.21931122757;20369.49076273094;20635.587288254224;20944.581108377824;20948.685769713466;6327.7785277515295;6423.614583125004;6697.5981441113445;6704.128463299764;6732.4950628703045;5904.468597826195;6506.089147519351;6599.703013364386;6609.966950165266;6634.568753031038;4648.140094082589;6552.115464412314;6553.541252112354;6555.77054803082;7312.780616581511;7226.241244669287;7493.66278517468;7531.623418829064;7553.962230188841;7558.697652093937;7031.402330801888;7120.537165084253;7138.571685698875;7159.427245820374;7173.569585824867;25411;25706;25842.353941151432;26014.86992565044;26092.73907260922;2184.9781502184933;2341.8477798943186;2358;2370.5969985102574;2480.0203919451897;14620.415183392652;14918.254087295592;14939.70120597582;15711.450099246471;15965.840341596551;1868.9626207475774;1893.9526511837178;1905.8570607204376;1931.0248324595989;2025.9696104558443;94855;104875.13436492294;105704.88590228229;106628;107920.46039769829;18731.719024214646;18851.90574047121;18935.621287574173;18998.905005474884;19053.237870485304;26893.596596051077;27033;27336.86331568369;27379.452410951668;27448;10590.682279531626;10610.734731631617;10611.628592999208;10642.201834862262;10718.571257149626;9063.456192628464;9145.131212534798;9180.127887850644;9182.265418766483;9200.769980750401;8479.745607631781;8577.099404562556;8640.69757558495;8662;8679.479231246145;1836.6288234003255;3382.9492557611657;3530.8940731777575;3566.7146628269675;3644.3440180767716;849.2229609906866;873.6243415331342;884.9336299777542;956.139474472966;988.41189492252;19183.136758845885;19225.51936201606;19317.903410482857;19390.806091939183;19635.74671922553;931.3294428011956;937.0676177203726;939.9436033837992;964.8215080210183;984.1142971325864;10347.379157250589;10384.42885641297;10491.793443753913;10604.151667866552;10605.787884242427;681752;682160;703412;757680;785896;112769;116785.82499225142;117730;119019;119573;1183.0476466444595;1190.220405634311;1214.6902539852458;1236.2458900070949;1264.6458991482216;16731.24709388056;17390.73913891318;17540.386086486913;17582.208800604258;17937.551561211076;45457.77271113623;45922.31116533188;46316;47068.35260765857;47294.52705473039;61987;62011.37988620056;62333.68833155805;62369.75260494855;62932.11203663897;7579.204183560647;7669.693212271447;7694;7746.612669366396;7759.418043646636;9698.515074246401;11938.641840744443;11955.940220298842;12191.53310708705;12229.205101668364;7776.8756008747605;9552.37909535878;9883.406995580288;10320.114191030247;10358.896411035794;10343.637972671222;10373;10478.318909270873;10687.25189236746;10896.637920259967;4099.036726369361;7166.53417527859;8682.826343473222;10406.531706073094;10438.321509101885;7694.884576731241;7707.961460192663;7711.614419278902;7718.768436946901;7728.343090837342;10313.381197128196;10707.571697132027;10749.193810464403;10868.67393978182;10903.182261330274 05:29:21 INFO - PROCESS | 2729 | |3;mootools.html;1568.6784209237105;1591.8965267257624;1858.191686616322;1942.426984039708;1956.853236007298;7906.6837326506875;8537;8749.256313213376;8976.551172441375;8980.8652870207;8539.957300213506;8659.134086591353;8707.34694897882;8736.956315218431;8775.824483510325;2489.0417189382083;2498.088197807796;2510.7991360691194;2511.0834545390894;2531.3545046013332;2462.876856157185;2516.245126462063;2520.143151328548;2527.8230523863335;2529.456166924111;1959.9706004409945;2098.380977611608;2381.833271670984;2461.704595448549;2503.68703912011;2454.8772561371948;2460.803135749136;2494.0896572751;2512.982242191906;2516.3709072731817;2126.9574608507896;2496.900123995038;2503.2615378463397;2510.259473455335;2517.0561039610147;8650.610722517502;8689.956550217272;8715;8739.563021848882;8798.516081615573;5407.5133238008375;5463.31708536433;5537.723113844332;5571.470710282557;5589.35722391922;3634.291313193928;3704.8888533344043;3758.7180961428003;3762.454444105617;3771.17034252464;1076.531708706715;1640.1471234957755;1666.1419369024961;1688.4765722626025;1708.92309846056;11721.472533736003;11726.29642221461;11823.586174483939;11896.762064758655;11925;4668.533146685304;4681.7425041622355;4705;4770.332153498513;4787.353707249476;9082.273418126533;9090.772730681785;9301.72094837156;9343.299252556086;9395.859062114074;5264.236685680555;5352.357717073976;5411.64824286428;5435.972820135953;5437.8368648940595;4981.501849815062;5050.974745126325;5057.772400241998;5064.34163558735;5071.0872043032605;3598.0285322962654;3624.655657712513;3630.5098811660614;3632.164602141522;3653.707703383723;1131.6605018494583;1171.1450641032095;1193.5106606291379;1204.584418375659;1211.0432758121162;1135.7217481716866;1135.7217481716866;1151.78691941991;1303.4916382610788;1388.8750012498722;7880.093789214314;7937.285644292041;7958.681652733826;8008.519488830689;8060.355171586259;644.754993102618;645.4287955159717;649.9447546958467;656.4387448731266;798.1419973528364;5994.190784244159;6108.419700128481;6162.7843025494785;6219.502439804804;6258.843528911813;10547.84178237327;10595.84106238407;10867.619633312954;10914.454277286104;10949.069329107115;9088.272938165062;10680.198985076151;10707.036366727032;10781.676549703521;10792;6826.4538836892925;8287.046989596152;8362.958185209156;8364.749057528285;8384.874226886479;7828.13890472059;7857.057153141431;7910.9604451977375;7925.247101525346;7958.602069896598;9468.810623787624;9599.616015359308;9710.465924374228;9766.169875560372;9864.506774661382;9758.121769040821;9914.950425247827;10020.949895250476;10078.445685487368;10136.493175341056;6734.764283250062;7470.252974702486;7552.584607846621;7632.732854350071;7637.961810191136;8069.757907262791;8215.753527394188;8242.340612750846;8250.917490825015;8328.875066874123;7125.429965602843;7732.072151341875;7736.342410895137;7868.213178682315;7884.566348850639;7594.582297973665;7719.150893401834;7732.884006740016;7804.985351904444;7831.060272767304;6174.44430001302;7846.80382990407;7883.369330453665;7900.881486777818;7921.366290696616;6860.2453730088655;7041.788746337618;7059.823504412226;7061.682224299816;7157.785266442015;7092.645367731696;7115;7155.356017958408;7162.068931038932;7170.677319520739 05:29:21 INFO - PROCESS | 2729 | |4;prototype.html;321.8085239282627;329.41035546371995;331.6766153000823;335.0083752093801;338.7408632396219;5866.911996320054;6187.793380290845;6864.588124712508;6951.131108611424;7033.437325014;6835.555688880232;6887.104676392062;6917.965410172955;6923.238443771181;6933.583984960893;5059.4181669107975;5064.290999260106;5076.187809950408;5080.6028342206;5087.66930149541;4138.503379594453;5050.595952323823;5066.240063990394;5075.543201111899;5104.821331253407;4785.377900872882;5050.898982020376;5059.696418214919;5066;5071.7717702703285;5058.570021548191;5065.1895696688725;5069.594432445432;5072.746362681851;5078.46676099008;4359.869203923856;4367.978160109211;4381.1237752449315;4388.736675799462;4393.53867843875;6497.447716944065;6602.834929126762;6711.161104861892;6713.966430167818;6733.966330168318;5948.97025514874;5953.672548009858;5954.404559544054;5958.016927207005;5969.194158788551;6319.842003949938;7391.519551229153;7406.59263740494;7422.331990120915;7438.218987006395;3786.981065094657;4135;4780.139574876521;4851.636127290467;4862.100511405401;6261.436470717657;6313.936860631381;7921.56431396273;7969.880451793228;8019.879701804419;4942.728149951715;5012.649114562018;5016.648834581617;5017.67385119966;5036.924446133311;5026.648134630541;5138.948610513922;5165.638405311667;5171.4311425743135;5189.76646050921;5975.850603734942;6006.249218847644;6008.729607167688;6024.367441418588;6038.879222415527;7476.140243871915;7477.102747670315;7484.176740558534;7503.2496750324535;7514.699412023567;1686.932522699103;1739.8956062636282;1759.5601099725068;1792.6056267621102;1796.6586348593726;1123.8819923907872;1124.6063877642923;1138.0895283773114;1139.566964553481;1142.954281828734;7045.189803172599;7097;7108.8933665995055;7136.714531418789;7141.928580714126;7787.961060194776;7793.10379306387;7802.531848089133;7832.334251588565;7838;6828.658567071627;7086.397656199178;7086.92913070873;7093.680784364716;7142.357187853118;4111.650509706648;4114.19773144234;4127.4015267786;4144.979275103605;4152.626263636287;8132.024157101186;8132.390070744599;8140.267375936194;8151.79620509492;8159.877601835978;6702.631355275409;7847.568383738835;8019.558924259221;8020.598970051474;8130.471519351223;6551.967240163769;8071.5964201789675;8123.7562873115085;8129.105798362294;8160.061592916772;5512.503874651301;5581.358143813351;7078.752243671447;7462.253774622712;7742.883856742041;6429.38920802523;7613.314801667876;7642.388608911163;7701.152873184057;7736.729214477691;8163.510189388656;8228.382871284557;8233.752987410388;8241.217084376975;8246.917530824616;4653.604443622195;4801.159797035435;4801.855944321676;4821.9035619288115;4839.685420447659;7745.612719363897;7780.610969451618;7785.416093792875;7807.258310460497;7809.1409944907155;6778.796636100925;6809.604031173499;7279.963600182177;7503.362214211853;7508.887366689395;7399.926000739923;7509.8498030038;7552.282533159341;7554.093508778982;7595.696172153052;6083.722418292031;7384.150822655463;7410.6665200065045;7411.592362420119;7418.1839997599145;7321.377682897013;7565.621718914143;7576.469647124648;7587.848243035219;7615.65729542183;7390.926090739239;7405.148407933157;7432.070991126109;7432.21961694036;7438.404927605887 05:29:21 INFO - PROCESS | 2729 | |5;yui.html;689.8930665746811;716.8028792082179;731.3052600029973;741.6106544064365;744.3933194446525;14748.115113093208;14784;14857.702845943075;14942.5517234483;14995.850041499581;13482.697338205637;13832.93083534581;13943.233122178277;13970.580882573538;13978.440862365518;709.2411120101493;858.3004851046393;870.2994089757742;876.0757400942003;893.3433926064338;872.2498651159997;881.7575166829109;882.9381943263974;889.6797153024905;898.6225785170244;916.463868636845;916.8533034714447;931.9655182747146;936.7002559181064;937.4515908193681;891.2468963725642;928.6703220356744;930.2790337488467;930.6649606141783;931.1293940165932;681.2200030964567;687.2337343861608;687.4534744877834;692.6155983429195;693.2547511425238;14325.283735813167;14340.856591434056;14390.856091439056;14438.639034024129;14468.565943021726;12770.87229127706;12886.67783305425;12896.935515322364;13083.476660933551;13192.53826116091;10048.397096174253;10280.331778434378;10293.245338131797;10306.278560500694;10650.786984260349;3515.6308587598446;3548.3258180945795;3550.9822450887837;3552.378333791576;3555.6266592007723;65363;68538;71241.57516849686;71246.9312960306;71361.28638713599;9227.677031303872;9290.62837486493;9301.162895339452;9469.573869175903;9848.753781155385;19911.60176796456;19931.202751889763;19931.900340498203;19974.30089946845;19986.400407987785;7173.89239161413;7399;7414.814629634302;7531.209223031612;7612.429067819936;4180.7909604519655;4199.832006719697;4214.810333534998;4250.022494826208;4250.723702959298;3489.808060556643;3514.9982255057184;3535.310614430213;3538.5046093546925;3553.591336996227;594.4382558482192;595.553334998751;599.2090440618429;604.7641419846263;626.946709529686;527.860117068977;543.9401665816757;563.0934195944526;602.6805792929754;623.8783437229697;8025.638846251816;8134.59327033646;8463.49219046859;8800.559972001374;8881.777955551033;455.80628233001505;519.7271432497894;545.912653975362;580.9099589563624;610.8656095658948;3481.742568721249;3979.263836190314;4030.274550580865;4252.936205956913;4279.2083464559155;85702.14297857066;87586.56206718925;88768.5561572188;90001.54999224962;90300.09699902916;67755.66122169457;68090.65954670294;70882.9367559487;76740;77480.61259693778;53494;72455.63772181;72767.63616181885;72895;73072.17319567053;7497.437692173;7985.72049978248;8733.039365669898;8944.239739621944;8975.416597921114;49806.003879921474;50244.4926652201;50815.49184508107;51168;51216.975660487326;64743.70512589816;65875.35311617248;66589;68105.97841032289;68851.62296754136;10524.842127368249;10794.676159715222;10805.45972701346;10899.182561308093;11213.159013074215;11616.361100139573;11911.225770324902;12020.459079341626;12065.939670301592;12131.636050918487;11085.224034317844;11213.775724485628;11612.12909031809;11621;11651.666018798398;11661.24201926872;11714.648560543197;11834.467448964646;11942.641720748044;11944.343061131722;11314.660560183207;11587.536498539965;11651.650450486499;11749.236299640495;11812.527498900292;9132.634694612141;9249.121333473578;9249.260059195114;9427.575759090994;9566.473843938371;7916.445848790529;9710.22318214527;9879.209663227068;9935.751606209902;9937.155341795738 05:29:21 INFO - PROCESS | 2729 | __end_tp_report 05:29:21 INFO - PROCESS | 2729 | __start_cc_report 05:29:21 INFO - PROCESS | 2729 | _x_x_mozilla_cycle_collect,370 05:29:21 INFO - PROCESS | 2729 | __end_cc_report 05:29:21 INFO - PROCESS | 2729 | __startTimestamp1454765361010__endTimestamp 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | ------- Summary: start ------- 05:29:21 INFO - PROCESS | 2729 | Number of tests: 6 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#0] dojo.html Cycles:165 Average:5676.65 Median:6000.97 stddev:765.50 (12.8%) stddev-sans-first:734.55 05:29:21 INFO - PROCESS | 2729 | Values: 2829.8 3449.5 3463.2 3482.2 3506.0 5603.7 5627.0 5689.4 5689.9 5764.3 5552.8 5578.2 5711.0 5830.8 5895.0 5011.4 5043.5 5060.6 5062.7 5093.5 786.1 5039.7 5040.0 5047.0 5162.1 5110.9 5137.2 5137.9 5183.2 5189.2 4342.8 5192.4 5196.4 5216.5 5243.7 4358.6 5071.6 5102.3 5125.4 5200.2 5449.8 5563.6 5568.5 5611.6 5669.5 6015.1 6193.5 6205.7 6209.1 6211.6 6198.7 6234.8 6243.5 6249.5 6269.8 5851.2 5851.9 5854.9 5857.6 5904.8 6192.4 6253.4 6341.0 6354.4 6355.7 5048.9 5930.1 5972.4 6009.0 6053.9 6105.6 6110.2 6124.0 6128.8 6151.9 6140.5 6153.9 6167.0 6180.2 6200.4 6100.1 6127.7 6181.2 6191.2 6213.5 4775.9 4913.4 4915.7 4916.5 4971.8 4403.9 4413.8 4414.8 4416.8 4468.7 5811.4 5827.2 5867.9 5895.1 5901.6 6122.5 6132.5 6197.3 6201.5 6218.1 5847.7 5851.0 5854.5 5854.5 5874.0 5266.3 5341.1 5359.3 5385.1 5391.4 5665.6 6286.8 6397.6 6443.9 6464.9 6211.4 6219.1 6230.5 6232.8 6246.5 6189.4 6284.8 6344.3 6387.7 6413.7 6036.6 6053.9 6056.0 6063.8 6066.0 5108.8 6108.7 6131.2 6161.8 6174.2 6082.4 6167.0 6173.3 6190.5 6191.5 6078.0 6099.2 6136.2 6142.9 6173.6 5857.0 5993.4 6024.2 6035.0 6083.2 6000.0 6098.1 6126.5 6130.0 6137.6 6001.0 6017.8 6026.2 6029.9 6032.6 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#1] ext.html Cycles:180 Average:57375.95 Median:36901.54 stddev:115961.37 (314.2%) stddev-sans-first:116207.94 05:29:21 INFO - PROCESS | 2729 | Values: 463.9 472.4 551.4 569.8 601.5 36836.3 36966.8 37187.6 37280.6 37339.8 37691.0 38193.2 38208.0 38308.0 38363.8 9431.2 9623.8 9704.8 9710.4 9713.8 9408.6 9596.7 9614.6 9624.0 9680.0 9597.1 9626.6 9662.1 9664.7 9690.0 9536.0 9562.8 9586.1 9593.9 9611.1 9447.9 9603.9 9621.8 9627.4 9630.7 37521.2 37694.8 37807.0 37879.0 37887.4 2688.0 2790.7 3322.3 3808.9 4058.1 25744.0 26342.0 26426.0 26559.0 26738.9 2762.4 2816.6 2866.4 2885.1 2973.0 321760.0 322395.0 323968.0 324049.0 324353.0 49288.8 59324.7 59634.4 59914.7 60086.7 61429.7 62300.4 63050.7 63176.7 63465.0 13459.4 14428.9 16541.9 16600.1 16632.6 12567.7 12626.5 12662.7 12668.7 12723.8 18750.1 19356.0 19457.6 19457.8 19478.5 6914.9 8288.3 8350.4 8575.4 8576.9 951.1 988.4 990.6 1086.4 1135.5 27549.7 29265.9 29283.0 29417.1 29922.7 1062.1 1096.2 1162.4 1170.5 1190.3 13750.6 14081.4 14162.4 14302.5 14398.6 508900.0 511753.4 512292.0 512988.0 516209.0 53723.7 54361.0 54541.9 54600.7 54961.9 1388.8 1404.2 1439.5 1449.5 1456.0 25784.2 26949.4 27215.7 27415.7 27498.9 47158.8 47308.0 47604.3 47635.0 47831.0 449647.0 451734.0 452058.0 452881.0 454220.0 29378.6 29655.7 29666.3 30022.0 31323.8 39527.0 40249.2 41005.6 41183.0 41348.8 33838.2 34749.5 35213.8 35795.5 35838.8 34976.7 35327.8 35483.5 35765.5 35791.1 31005.7 34321.5 35343.8 35348.6 35646.0 30790.5 30951.7 32068.8 33650.5 33956.5 21727.9 24087.5 24389.5 24818.8 24954.9 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#2] jquery.html Cycles:180 Average:37893.99 Median:27358.16 stddev:118997.63 (435.0%) stddev-sans-first:119297.99 05:29:21 INFO - PROCESS | 2729 | Values: 316.5 359.7 360.2 363.7 367.1 15824.8 16959.9 18572.0 18772.6 19413.6 19517.2 20369.5 20635.6 20944.6 20948.7 6327.8 6423.6 6697.6 6704.1 6732.5 5904.5 6506.1 6599.7 6610.0 6634.6 4648.1 6552.1 6553.5 6555.8 7312.8 7226.2 7493.7 7531.6 7554.0 7558.7 7031.4 7120.5 7138.6 7159.4 7173.6 25411.0 25706.0 25842.4 26014.9 26092.7 2185.0 2341.8 2358.0 2370.6 2480.0 14620.4 14918.3 14939.7 15711.5 15965.8 1869.0 1894.0 1905.9 1931.0 2026.0 94855.0 104875.1 105704.9 106628.0 107920.5 18731.7 18851.9 18935.6 18998.9 19053.2 26893.6 27033.0 27336.9 27379.5 27448.0 10590.7 10610.7 10611.6 10642.2 10718.6 9063.5 9145.1 9180.1 9182.3 9200.8 8479.7 8577.1 8640.7 8662.0 8679.5 1836.6 3382.9 3530.9 3566.7 3644.3 849.2 873.6 884.9 956.1 988.4 19183.1 19225.5 19317.9 19390.8 19635.7 931.3 937.1 939.9 964.8 984.1 10347.4 10384.4 10491.8 10604.2 10605.8 681752.0 682160.0 703412.0 757680.0 785896.0 112769.0 116785.8 117730.0 119019.0 119573.0 1183.0 1190.2 1214.7 1236.2 1264.6 16731.2 17390.7 17540.4 17582.2 17937.6 45457.8 45922.3 46316.0 47068.4 47294.5 61987.0 62011.4 62333.7 62369.8 62932.1 7579.2 7669.7 7694.0 7746.6 7759.4 9698.5 11938.6 11955.9 12191.5 12229.2 7776.9 9552.4 9883.4 10320.1 10358.9 10343.6 10373.0 10478.3 10687.3 10896.6 4099.0 7166.5 8682.8 10406.5 10438.3 7694.9 7708.0 7711.6 7718.8 7728.3 10313.4 10707.6 10749.2 10868.7 10903.2 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#3] mootools.html Cycles:180 Average:6039.30 Median:5580.41 stddev:3160.92 (56.6%) stddev-sans-first:3151.92 05:29:21 INFO - PROCESS | 2729 | Values: 1568.7 1591.9 1858.2 1942.4 1956.9 7906.7 8537.0 8749.3 8976.6 8980.9 8540.0 8659.1 8707.3 8737.0 8775.8 2489.0 2498.1 2510.8 2511.1 2531.4 2462.9 2516.2 2520.1 2527.8 2529.5 1960.0 2098.4 2381.8 2461.7 2503.7 2454.9 2460.8 2494.1 2513.0 2516.4 2127.0 2496.9 2503.3 2510.3 2517.1 8650.6 8690.0 8715.0 8739.6 8798.5 5407.5 5463.3 5537.7 5571.5 5589.4 3634.3 3704.9 3758.7 3762.5 3771.2 1076.5 1640.1 1666.1 1688.5 1708.9 11721.5 11726.3 11823.6 11896.8 11925.0 4668.5 4681.7 4705.0 4770.3 4787.4 9082.3 9090.8 9301.7 9343.3 9395.9 5264.2 5352.4 5411.6 5436.0 5437.8 4981.5 5051.0 5057.8 5064.3 5071.1 3598.0 3624.7 3630.5 3632.2 3653.7 1131.7 1171.1 1193.5 1204.6 1211.0 1135.7 1135.7 1151.8 1303.5 1388.9 7880.1 7937.3 7958.7 8008.5 8060.4 644.8 645.4 649.9 656.4 798.1 5994.2 6108.4 6162.8 6219.5 6258.8 10547.8 10595.8 10867.6 10914.5 10949.1 9088.3 10680.2 10707.0 10781.7 10792.0 6826.5 8287.0 8363.0 8364.7 8384.9 7828.1 7857.1 7911.0 7925.2 7958.6 9468.8 9599.6 9710.5 9766.2 9864.5 9758.1 9915.0 10020.9 10078.4 10136.5 6734.8 7470.3 7552.6 7632.7 7638.0 8069.8 8215.8 8242.3 8250.9 8328.9 7125.4 7732.1 7736.3 7868.2 7884.6 7594.6 7719.2 7732.9 7805.0 7831.1 6174.4 7846.8 7883.4 7900.9 7921.4 6860.2 7041.8 7059.8 7061.7 7157.8 7092.6 7115.0 7155.4 7162.1 7170.7 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#4] prototype.html Cycles:180 Average:6050.57 Median:6756.38 stddev:1952.07 (28.9%) stddev-sans-first:1909.60 05:29:21 INFO - PROCESS | 2729 | Values: 321.8 329.4 331.7 335.0 338.7 5866.9 6187.8 6864.6 6951.1 7033.4 6835.6 6887.1 6918.0 6923.2 6933.6 5059.4 5064.3 5076.2 5080.6 5087.7 4138.5 5050.6 5066.2 5075.5 5104.8 4785.4 5050.9 5059.7 5066.0 5071.8 5058.6 5065.2 5069.6 5072.7 5078.5 4359.9 4368.0 4381.1 4388.7 4393.5 6497.4 6602.8 6711.2 6714.0 6734.0 5949.0 5953.7 5954.4 5958.0 5969.2 6319.8 7391.5 7406.6 7422.3 7438.2 3787.0 4135.0 4780.1 4851.6 4862.1 6261.4 6313.9 7921.6 7969.9 8019.9 4942.7 5012.6 5016.6 5017.7 5036.9 5026.6 5138.9 5165.6 5171.4 5189.8 5975.9 6006.2 6008.7 6024.4 6038.9 7476.1 7477.1 7484.2 7503.2 7514.7 1686.9 1739.9 1759.6 1792.6 1796.7 1123.9 1124.6 1138.1 1139.6 1143.0 7045.2 7097.0 7108.9 7136.7 7141.9 7788.0 7793.1 7802.5 7832.3 7838.0 6828.7 7086.4 7086.9 7093.7 7142.4 4111.7 4114.2 4127.4 4145.0 4152.6 8132.0 8132.4 8140.3 8151.8 8159.9 6702.6 7847.6 8019.6 8020.6 8130.5 6552.0 8071.6 8123.8 8129.1 8160.1 5512.5 5581.4 7078.8 7462.3 7742.9 6429.4 7613.3 7642.4 7701.2 7736.7 8163.5 8228.4 8233.8 8241.2 8246.9 4653.6 4801.2 4801.9 4821.9 4839.7 7745.6 7780.6 7785.4 7807.3 7809.1 6778.8 6809.6 7280.0 7503.4 7508.9 7399.9 7509.8 7552.3 7554.1 7595.7 6083.7 7384.2 7410.7 7411.6 7418.2 7321.4 7565.6 7576.5 7587.8 7615.7 7390.9 7405.1 7432.1 7432.2 7438.4 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - PROCESS | 2729 | [#5] yui.html Cycles:180 Average:17644.00 Median:600.94 stddev:24235.89 (4033.0%) stddev-sans-first:24270.45 05:29:21 INFO - PROCESS | 2729 | Values: 689.9 716.8 731.3 741.6 744.4 14748.1 14784.0 14857.7 14942.6 14995.9 13482.7 13832.9 13943.2 13970.6 13978.4 709.2 858.3 870.3 876.1 893.3 872.2 881.8 882.9 889.7 898.6 916.5 916.9 932.0 936.7 937.5 891.2 928.7 930.3 930.7 931.1 681.2 687.2 687.5 692.6 693.3 14325.3 14340.9 14390.9 14438.6 14468.6 12770.9 12886.7 12896.9 13083.5 13192.5 10048.4 10280.3 10293.2 10306.3 10650.8 3515.6 3548.3 3551.0 3552.4 3555.6 65363.0 68538.0 71241.6 71246.9 71361.3 9227.7 9290.6 9301.2 9469.6 9848.8 19911.6 19931.2 19931.9 19974.3 19986.4 7173.9 7399.0 7414.8 7531.2 7612.4 4180.8 4199.8 4214.8 4250.0 4250.7 3489.8 3515.0 3535.3 3538.5 3553.6 594.4 595.6 599.2 604.8 626.9 527.9 543.9 563.1 602.7 623.9 8025.6 8134.6 8463.5 8800.6 8881.8 455.8 519.7 545.9 580.9 610.9 3481.7 3979.3 4030.3 4252.9 4279.2 85702.1 87586.6 88768.6 90001.5 90300.1 67755.7 68090.7 70882.9 76740.0 77480.6 53494.0 72455.6 72767.6 72895.0 73072.2 7497.4 7985.7 8733.0 8944.2 8975.4 49806.0 50244.5 50815.5 51168.0 51217.0 64743.7 65875.4 66589.0 68106.0 68851.6 10524.8 10794.7 10805.5 10899.2 11213.2 11616.4 11911.2 12020.5 12065.9 12131.6 11085.2 11213.8 11612.1 11621.0 11651.7 11661.2 11714.6 11834.5 11942.6 11944.3 11314.7 11587.5 11651.7 11749.2 11812.5 9132.6 9249.1 9249.3 9427.6 9566.5 7916.4 9710.2 9879.2 9935.8 9937.2 05:29:21 INFO - PROCESS | 2729 | -------- Summary: end -------- 05:29:21 INFO - PROCESS | 2729 | 05:29:21 INFO - TEST-INFO | 2729: exit 0 05:29:21 INFO - TEST-OK | dromaeo_css | took 1110783ms 05:29:21 INFO - TEST-START | kraken 05:29:21 INFO - operating with platform_type : mac_ 05:29:21 INFO - Initialising browser for kraken test... 05:29:21 INFO - TEST-INFO | started process 2735 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpEgGKyt/profile http://localhost:52949/getInfo.html) 05:29:24 INFO - PROCESS | 2735 | __metrics Screen width/height:1600/1200 05:29:24 INFO - PROCESS | 2735 | colorDepth:24 05:29:24 INFO - PROCESS | 2735 | Browser inner width/height: 1024/689 05:29:24 INFO - PROCESS | 2735 | __metrics 05:29:24 INFO - TEST-INFO | 2735: exit 0 05:29:24 INFO - Browser initialized. 05:29:24 INFO - Running cycle 1/1 for kraken test... 05:29:24 INFO - TEST-INFO | started process 2739 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpEgGKyt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:37 INFO - PROCESS | 2739 | RSS: Main: 258138112 05:29:37 INFO - PROCESS | 2739 | 05:29:39 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52949/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:40 INFO - PROCESS | 2739 | RSS: Main: 271720448 05:29:40 INFO - PROCESS | 2739 | 05:29:43 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52949/tests/kraken/driver.html?testName=audio-dft) 05:29:43 INFO - PROCESS | 2739 | RSS: Main: 278683648 05:29:43 INFO - PROCESS | 2739 | 05:29:47 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52949/tests/kraken/driver.html?testName=audio-fft) 05:29:47 INFO - PROCESS | 2739 | RSS: Main: 283205632 05:29:47 INFO - PROCESS | 2739 | 05:29:49 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52949/tests/kraken/driver.html?testName=audio-oscillator) 05:29:50 INFO - PROCESS | 2739 | RSS: Main: 284422144 05:29:50 INFO - PROCESS | 2739 | 05:29:52 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52949/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:52 INFO - PROCESS | 2739 | RSS: Main: 285003776 05:29:52 INFO - PROCESS | 2739 | 05:29:55 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52949/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:56 INFO - PROCESS | 2739 | RSS: Main: 285896704 05:29:56 INFO - PROCESS | 2739 | 05:29:59 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52949/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:00 INFO - PROCESS | 2739 | RSS: Main: 285712384 05:30:00 INFO - PROCESS | 2739 | 05:30:03 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52949/tests/kraken/driver.html?testName=json-parse-financial) 05:30:03 INFO - PROCESS | 2739 | RSS: Main: 286040064 05:30:03 INFO - PROCESS | 2739 | 05:30:05 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52949/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:05 INFO - PROCESS | 2739 | RSS: Main: 286625792 05:30:05 INFO - PROCESS | 2739 | 05:30:07 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:08 INFO - PROCESS | 2739 | RSS: Main: 284844032 05:30:08 INFO - PROCESS | 2739 | 05:30:10 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:30:11 INFO - PROCESS | 2739 | RSS: Main: 285061120 05:30:11 INFO - PROCESS | 2739 | 05:30:13 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:30:14 INFO - PROCESS | 2739 | RSS: Main: 286711808 05:30:14 INFO - PROCESS | 2739 | 05:30:17 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:30:17 INFO - PROCESS | 2739 | RSS: Main: 286117888 05:30:17 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | Cycle 1(1): loaded http://localhost:52949/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52949/tests/kraken/driver.html?testName=ai-astar) 05:30:19 INFO - PROCESS | 2739 | RSS: Main: 286789632 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | __start_tp_report 05:30:19 INFO - PROCESS | 2739 | _x_x_mozilla_page_load 05:30:19 INFO - PROCESS | 2739 | _x_x_mozilla_page_load_details 05:30:19 INFO - PROCESS | 2739 | |i|pagename|runs| 05:30:19 INFO - PROCESS | 2739 | |0;ai-astar;116;116;167;91;111;126;112;114;115;114 05:30:19 INFO - PROCESS | 2739 | |1;audio-beat-detection;170;111;178;176;149;143;153;106;184;151 05:30:19 INFO - PROCESS | 2739 | |2;audio-dft;202;192;201;218;231;196;193;198;245;223 05:30:19 INFO - PROCESS | 2739 | |3;audio-fft;72;75;73;128;78;58;71;85;143;73 05:30:19 INFO - PROCESS | 2739 | |4;audio-oscillator;82;85;131;78;80;81;117;79;82;119 05:30:19 INFO - PROCESS | 2739 | |5;imaging-gaussian-blur;98;140;85;92;87;113;77;140;131;94 05:30:19 INFO - PROCESS | 2739 | |6;imaging-darkroom;99;96;148;149;95;119;111;96;98;96 05:30:19 INFO - PROCESS | 2739 | |7;imaging-desaturate;151;102;113;121;98;97;99;95;116;150 05:30:19 INFO - PROCESS | 2739 | |8;json-parse-financial;57;56;55;56;55;57;54;65;55;55 05:30:19 INFO - PROCESS | 2739 | |9;json-stringify-tinderbox;67;65;70;71;71;68;67;64;65;66 05:30:19 INFO - PROCESS | 2739 | |10;stanford-crypto-aes;87;78;98;122;116;115;86;116;82;106 05:30:19 INFO - PROCESS | 2739 | |11;stanford-crypto-ccm;134;129;124;127;148;140;141;126;172;133 05:30:19 INFO - PROCESS | 2739 | |12;stanford-crypto-pbkdf2;142;139;141;143;183;145;142;142;152;139 05:30:19 INFO - PROCESS | 2739 | |13;stanford-crypto-sha256-iterative;69;84;96;61;88;98;99;69;65;71 05:30:19 INFO - PROCESS | 2739 | __end_tp_report 05:30:19 INFO - PROCESS | 2739 | __start_cc_report 05:30:19 INFO - PROCESS | 2739 | _x_x_mozilla_cycle_collect,916 05:30:19 INFO - PROCESS | 2739 | __end_cc_report 05:30:19 INFO - PROCESS | 2739 | __startTimestamp1454765419747__endTimestamp 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | ------- Summary: start ------- 05:30:19 INFO - PROCESS | 2739 | Number of tests: 14 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#0] ai-astar Cycles:10 Average:118.20 Median:116.00 stddev:19.23 (16.6%) stddev-sans-first:20.38 05:30:19 INFO - PROCESS | 2739 | Values: 116.0 116.0 167.0 91.0 111.0 126.0 112.0 114.0 115.0 114.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#1] audio-beat-detection Cycles:10 Average:152.10 Median:161.50 stddev:26.85 (16.6%) stddev-sans-first:27.69 05:30:19 INFO - PROCESS | 2739 | Values: 170.0 111.0 178.0 176.0 149.0 143.0 153.0 106.0 184.0 151.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#2] audio-dft Cycles:10 Average:209.90 Median:210.00 stddev:18.25 (8.7%) stddev-sans-first:19.13 05:30:19 INFO - PROCESS | 2739 | Values: 202.0 192.0 201.0 218.0 231.0 196.0 193.0 198.0 245.0 223.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#3] audio-fft Cycles:10 Average:85.60 Median:73.00 stddev:27.37 (37.5%) stddev-sans-first:28.58 05:30:19 INFO - PROCESS | 2739 | Values: 72.0 75.0 73.0 128.0 78.0 58.0 71.0 85.0 143.0 73.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:80.50 stddev:20.37 (25.3%) stddev-sans-first:21.18 05:30:19 INFO - PROCESS | 2739 | Values: 82.0 85.0 131.0 78.0 80.0 81.0 117.0 79.0 82.0 119.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:86.00 stddev:23.65 (27.5%) stddev-sans-first:24.92 05:30:19 INFO - PROCESS | 2739 | Values: 98.0 140.0 85.0 92.0 87.0 113.0 77.0 140.0 131.0 94.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:96.00 stddev:21.40 (22.3%) stddev-sans-first:22.27 05:30:19 INFO - PROCESS | 2739 | Values: 99.0 96.0 148.0 149.0 95.0 119.0 111.0 96.0 98.0 96.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#7] imaging-desaturate Cycles:10 Average:114.20 Median:123.00 stddev:21.06 (17.1%) stddev-sans-first:17.64 05:30:19 INFO - PROCESS | 2739 | Values: 151.0 102.0 113.0 121.0 98.0 97.0 99.0 95.0 116.0 150.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.00 stddev:3.14 (5.6%) stddev-sans-first:3.32 05:30:19 INFO - PROCESS | 2739 | Values: 57.0 56.0 55.0 56.0 55.0 57.0 54.0 65.0 55.0 55.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:2.55 (3.8%) stddev-sans-first:2.70 05:30:19 INFO - PROCESS | 2739 | Values: 67.0 65.0 70.0 71.0 71.0 68.0 67.0 64.0 65.0 66.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#10] stanford-crypto-aes Cycles:10 Average:100.60 Median:80.00 stddev:16.43 (20.5%) stddev-sans-first:16.68 05:30:19 INFO - PROCESS | 2739 | Values: 87.0 78.0 98.0 122.0 116.0 115.0 86.0 116.0 82.0 106.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:137.00 stddev:14.33 (10.5%) stddev-sans-first:15.15 05:30:19 INFO - PROCESS | 2739 | Values: 134.0 129.0 124.0 127.0 148.0 140.0 141.0 126.0 172.0 133.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.80 Median:142.50 stddev:13.25 (9.3%) stddev-sans-first:13.94 05:30:19 INFO - PROCESS | 2739 | Values: 142.0 139.0 141.0 143.0 183.0 145.0 142.0 142.0 152.0 139.0 05:30:19 INFO - PROCESS | 2739 | 05:30:19 INFO - PROCESS | 2739 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.00 Median:86.00 stddev:14.64 (17.0%) stddev-sans-first:14.98 05:30:19 INFO - PROCESS | 2739 | Values: 69.0 84.0 96.0 61.0 88.0 98.0 99.0 69.0 65.0 71.0 05:30:19 INFO - PROCESS | 2739 | -------- Summary: end -------- 05:30:19 INFO - PROCESS | 2739 | 05:30:20 INFO - TEST-INFO | 2739: exit 0 05:30:20 INFO - TEST-OK | kraken | took 58993ms 05:30:20 INFO - SUITE-END | took 1169s 05:30:20 INFO - Completed test suite (00:19:30) 05:30:20 INFO - Working with test: dromaeo_css 05:30:20 INFO - Generating results file: dromaeo_css 05:30:20 INFO - Working with test: kraken 05:30:20 INFO - Generating results file: kraken 05:30:20 INFO - javascript benchmark 05:30:20 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:30:21 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,61]] 05:30:21 INFO - process_Request line: dromaeo_css 7629.90 graph.html#tests=[[72,1,61]] 05:30:21 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:30:21 INFO - process_Request line: kraken 1578.50 graph.html#tests=[[232,1,61]] 05:30:21 INFO - RETURN: dromaeo_css: 7629.90 05:30:21 INFO - RETURN: kraken: 1578.50 05:30:21 INFO - javascript benchmark 05:30:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2829.8160619559726, 3449.465332873404, 3463.2207753255507, 3482.2339085401204, 3505.964940350596, 5603.719814009293, 5627.0, 5689.402612725667, 5689.8577535561635, 5764.308283006035, 5552.805651802188, 5578.219049333086, 5711.0, 5830.825075247739, 5895.027320492124, 5011.448740638536, 5043.546080852722, 5060.569851562622, 5062.746862656871, 5093.465186155456, 786.0839118830833, 5039.697618142924, 5040.0, 5047.0, 5162.096633089213, 5110.872228194306, 5137.229415587654, 5137.948620513803, 5183.248428977815, 5189.195674670432, 4342.782860856944, 5192.3769147702515, 5196.402413722432, 5216.504432078947, 5243.659162154486, 4358.629516491102, 5071.644984851062, 5102.336696229503, 5125.436202017775, 5200.245964335188, 5449.782008719647, 5563.582731295169, 5568.52667523261, 5611.635243709186, 5669.518090962273, 6015.0676645120075, 6193.504519638418, 6205.689715514206, 6209.130721698965, 6211.627302361828, 6198.690065496752, 6234.781782637586, 6243.469305109026, 6249.468795152417, 6269.780557680459, 5851.180834683148, 5851.941480585225, 5854.941450585482, 5857.619254748428, 5904.822855314347, 6192.442680158717, 6253.437190652863, 6340.968295158587, 6354.364563543609, 6355.713992870332, 5048.873778155576, 5930.140129681172, 5972.402759723993, 6009.0, 6053.939460605337, 6105.603135796247, 6110.175126357974, 6123.969380153071, 6128.78549250783, 6151.876962460726, 6140.508759299335, 6153.907691384633, 6166.982447896136, 6180.196574445294, 6200.441960223601, 6100.145979562864, 6127.662978536134, 6181.196444462281, 6191.164192834, 6213.5339849511465, 4775.856724298276, 4913.361263035854, 4915.680480768739, 4916.459189489154, 4971.751412429364, 4403.911921761547, 4413.7793110345, 4414.80133393998, 4416.779161041935, 4468.731876087445, 5811.360750317462, 5827.155062515912, 5867.882642347215, 5895.14520394549, 5901.616394934315, 6122.459144589048, 6132.478739307119, 6197.25632924043, 6201.534884883652, 6218.098375735493, 5847.678377689268, 5851.0, 5854.4730974212525, 5854.4730974212525, 5873.970630146822, 5266.2890509781455, 5341.118715411913, 5359.276497672842, 5385.111456609616, 5391.433899440581, 5665.575081868877, 6286.805506953664, 6397.552171347961, 6443.871122577522, 6464.903026454513, 6211.409916057967, 6219.0982307566055, 6230.470410015018, 6232.8130156095385, 6246.531510136849, 6189.381061893775, 6284.811455656338, 6344.333844946399, 6387.712552935222, 6413.679316034273, 6036.607620504828, 6053.909191362045, 6056.0, 6063.787767428119, 6066.0, 5108.821191258288, 6108.706347746941, 6131.162586540493, 6161.753529858755, 6174.166487524127, 6082.391760823883, 6166.969165154145, 6173.259208894961, 6190.535709821871, 6191.504679625709, 6078.02751559766, 6099.207103076749, 6136.20229370179, 6142.877142457215, 6173.56785025044, 5857.033589457692, 5993.3706960768495, 6024.216851809412, 6035.034394496859, 6083.178770866053, 6000.039993601003, 6098.054801505774, 6126.5098792097415, 6129.969350153221, 6137.570370074052, 6000.969995149996, 6017.849553761191, 6026.186464827368, 6029.939700602938, 6032.577719559588], "unit": "score", "name": "dojo.html", "value": 5627.776430029036}, {"lowerIsBetter": false, "replicates": [463.90981194193625, 472.4142063840836, 551.396221137854, 569.82050654044, 601.5398220361421, 36836.26327473449, 36966.8151659242, 37187.62812371876, 37280.62719372809, 37339.81330093353, 37691.0, 38193.23613527728, 38208.0, 38308.0, 38363.80818095906, 9431.245500359972, 9623.807523849518, 9704.805903881917, 9710.36882602632, 9713.805723885518, 9408.576614052385, 9596.664116755916, 9614.56734446948, 9623.95188024059, 9680.031996800333, 9597.136257736802, 9626.614935402575, 9662.08210220028, 9664.661736839213, 9689.951550242275, 9536.046395360478, 9562.808743825153, 9586.08932151448, 9593.90406095937, 9611.134997850191, 9447.90552094477, 9603.90396096044, 9621.807563848753, 9627.422354658673, 9630.662926797599, 37521.24957500862, 37694.81152594247, 37807.0, 37879.0, 37887.43168852442, 2688.01887311132, 2790.693023767384, 3322.2524931890375, 3808.9428658570146, 4058.127502586958, 25744.0, 26342.0, 26426.0, 26559.0, 26738.866305668347, 2762.4475104979083, 2816.5915942188267, 2866.4410439964217, 2885.1056172586564, 2973.0337640266785, 321760.0, 322395.0, 323968.0, 324049.0, 324353.0, 49288.75355623199, 59324.7033764837, 59634.40365596375, 59914.70042649759, 60086.69956650189, 61429.69285153546, 62300.37699622946, 63050.68474657597, 63176.68411658004, 63465.0, 13459.394327255297, 14428.85571144296, 16541.917290413712, 16600.08699521538, 16632.584185395463, 12567.748645027232, 12626.494940202288, 12662.683432914251, 12668.746625067632, 12723.809142862865, 18750.0924196195, 19356.0, 19457.610847782966, 19457.8054219456, 19478.513037174183, 6914.896276555855, 8288.336933045342, 8350.4154709171, 8575.442596231349, 8576.914230857612, 951.1059603972244, 988.3773222869546, 990.6384169777979, 1086.4079076903106, 1135.500379832871, 27549.724502754714, 29265.853670731936, 29283.0, 29417.11748647544, 29922.700772992426, 1062.1078294232727, 1096.221682605359, 1162.4187906046977, 1170.5142365918218, 1190.2739329009298, 13750.587482375544, 14081.436742530184, 14162.414477267914, 14302.499412520512, 14398.640033999234, 508900.0, 511753.4412327989, 512292.0, 512988.0, 516209.0, 53723.6569085776, 54361.0, 54541.90916181733, 54600.72699636476, 54961.90076198534, 1388.7986241994854, 1404.206623257856, 1439.5105664074056, 1449.528728340735, 1455.9636009099645, 25784.226473205836, 26949.377626258403, 27215.72784272132, 27415.725842741318, 27498.862505687342, 47158.76420618012, 47308.0, 47604.28593571168, 47635.047299053134, 47831.0, 449647.0, 451734.0, 452058.0, 452881.0, 454220.0, 29378.55932161062, 29655.703442965296, 29666.258343541584, 30022.0, 31323.84338078295, 39527.0, 40249.19501609893, 41005.58994410137, 41182.97042573959, 41348.793256033525, 33838.154046149044, 34749.47875781916, 35213.767518136745, 35795.463068053476, 35838.820805895804, 34976.65023349734, 35327.76352827639, 35483.467747984316, 35765.46351804673, 35791.105222369646, 31005.689943101184, 34321.48517772186, 35343.82328088343, 35348.64651353556, 35646.0, 30790.53814192744, 30951.690483095783, 32068.83965580157, 33650.49524257187, 33956.49065263974, 21727.891360543097, 24087.51824963526, 24389.51220975535, 24818.751812482365, 24954.87522562448], "unit": "score", "name": "ext.html", "value": 18931.057793924243}, {"lowerIsBetter": false, "replicates": [316.5015101215585, 359.714027348258, 360.1626219040729, 363.6726945748827, 367.1042655919554, 15824.84175158248, 16959.915200423995, 18572.0, 18772.624547509076, 19413.611727765452, 19517.21931122757, 20369.49076273094, 20635.587288254224, 20944.581108377824, 20948.685769713466, 6327.7785277515295, 6423.614583125004, 6697.5981441113445, 6704.128463299764, 6732.4950628703045, 5904.468597826195, 6506.089147519351, 6599.703013364386, 6609.966950165266, 6634.568753031038, 4648.140094082589, 6552.115464412314, 6553.541252112354, 6555.77054803082, 7312.780616581511, 7226.241244669287, 7493.66278517468, 7531.623418829064, 7553.962230188841, 7558.697652093937, 7031.402330801888, 7120.537165084253, 7138.571685698875, 7159.427245820374, 7173.569585824867, 25411.0, 25706.0, 25842.353941151432, 26014.86992565044, 26092.73907260922, 2184.9781502184933, 2341.8477798943186, 2358.0, 2370.5969985102574, 2480.0203919451897, 14620.415183392652, 14918.254087295592, 14939.70120597582, 15711.450099246471, 15965.840341596551, 1868.9626207475774, 1893.9526511837178, 1905.8570607204376, 1931.0248324595989, 2025.9696104558443, 94855.0, 104875.13436492294, 105704.88590228229, 106628.0, 107920.46039769829, 18731.719024214646, 18851.90574047121, 18935.621287574173, 18998.905005474884, 19053.237870485304, 26893.596596051077, 27033.0, 27336.86331568369, 27379.452410951668, 27448.0, 10590.682279531626, 10610.734731631617, 10611.628592999208, 10642.201834862262, 10718.571257149626, 9063.456192628464, 9145.131212534798, 9180.127887850644, 9182.265418766483, 9200.769980750401, 8479.745607631781, 8577.099404562556, 8640.69757558495, 8662.0, 8679.479231246145, 1836.6288234003255, 3382.9492557611657, 3530.8940731777575, 3566.7146628269675, 3644.3440180767716, 849.2229609906866, 873.6243415331342, 884.9336299777542, 956.139474472966, 988.41189492252, 19183.136758845885, 19225.51936201606, 19317.903410482857, 19390.806091939183, 19635.74671922553, 931.3294428011956, 937.0676177203726, 939.9436033837992, 964.8215080210183, 984.1142971325864, 10347.379157250589, 10384.42885641297, 10491.793443753913, 10604.151667866552, 10605.787884242427, 681752.0, 682160.0, 703412.0, 757680.0, 785896.0, 112769.0, 116785.82499225142, 117730.0, 119019.0, 119573.0, 1183.0476466444595, 1190.220405634311, 1214.6902539852458, 1236.2458900070949, 1264.6458991482216, 16731.24709388056, 17390.73913891318, 17540.386086486913, 17582.208800604258, 17937.551561211076, 45457.77271113623, 45922.31116533188, 46316.0, 47068.35260765857, 47294.52705473039, 61987.0, 62011.37988620056, 62333.68833155805, 62369.75260494855, 62932.11203663897, 7579.204183560647, 7669.693212271447, 7694.0, 7746.612669366396, 7759.418043646636, 9698.515074246401, 11938.641840744443, 11955.940220298842, 12191.53310708705, 12229.205101668364, 7776.8756008747605, 9552.37909535878, 9883.406995580288, 10320.114191030247, 10358.896411035794, 10343.637972671222, 10373.0, 10478.318909270873, 10687.25189236746, 10896.637920259967, 4099.036726369361, 7166.53417527859, 8682.826343473222, 10406.531706073094, 10438.321509101885, 7694.884576731241, 7707.961460192663, 7711.614419278902, 7718.768436946901, 7728.343090837342, 10313.381197128196, 10707.571697132027, 10749.193810464403, 10868.67393978182, 10903.182261330274], "unit": "score", "name": "jquery.html", "value": 10272.567242079427}, {"lowerIsBetter": false, "replicates": [1568.6784209237105, 1591.8965267257624, 1858.191686616322, 1942.426984039708, 1956.853236007298, 7906.6837326506875, 8537.0, 8749.256313213376, 8976.551172441375, 8980.8652870207, 8539.957300213506, 8659.134086591353, 8707.34694897882, 8736.956315218431, 8775.824483510325, 2489.0417189382083, 2498.088197807796, 2510.7991360691194, 2511.0834545390894, 2531.3545046013332, 2462.876856157185, 2516.245126462063, 2520.143151328548, 2527.8230523863335, 2529.456166924111, 1959.9706004409945, 2098.380977611608, 2381.833271670984, 2461.704595448549, 2503.68703912011, 2454.8772561371948, 2460.803135749136, 2494.0896572751, 2512.982242191906, 2516.3709072731817, 2126.9574608507896, 2496.900123995038, 2503.2615378463397, 2510.259473455335, 2517.0561039610147, 8650.610722517502, 8689.956550217272, 8715.0, 8739.563021848882, 8798.516081615573, 5407.5133238008375, 5463.31708536433, 5537.723113844332, 5571.470710282557, 5589.35722391922, 3634.291313193928, 3704.8888533344043, 3758.7180961428003, 3762.454444105617, 3771.17034252464, 1076.531708706715, 1640.1471234957755, 1666.1419369024961, 1688.4765722626025, 1708.92309846056, 11721.472533736003, 11726.29642221461, 11823.586174483939, 11896.762064758655, 11925.0, 4668.533146685304, 4681.7425041622355, 4705.0, 4770.332153498513, 4787.353707249476, 9082.273418126533, 9090.772730681785, 9301.72094837156, 9343.299252556086, 9395.859062114074, 5264.236685680555, 5352.357717073976, 5411.64824286428, 5435.972820135953, 5437.8368648940595, 4981.501849815062, 5050.974745126325, 5057.772400241998, 5064.34163558735, 5071.0872043032605, 3598.0285322962654, 3624.655657712513, 3630.5098811660614, 3632.164602141522, 3653.707703383723, 1131.6605018494583, 1171.1450641032095, 1193.5106606291379, 1204.584418375659, 1211.0432758121162, 1135.7217481716866, 1135.7217481716866, 1151.78691941991, 1303.4916382610788, 1388.8750012498722, 7880.093789214314, 7937.285644292041, 7958.681652733826, 8008.519488830689, 8060.355171586259, 644.754993102618, 645.4287955159717, 649.9447546958467, 656.4387448731266, 798.1419973528364, 5994.190784244159, 6108.419700128481, 6162.7843025494785, 6219.502439804804, 6258.843528911813, 10547.84178237327, 10595.84106238407, 10867.619633312954, 10914.454277286104, 10949.069329107115, 9088.272938165062, 10680.198985076151, 10707.036366727032, 10781.676549703521, 10792.0, 6826.4538836892925, 8287.046989596152, 8362.958185209156, 8364.749057528285, 8384.874226886479, 7828.13890472059, 7857.057153141431, 7910.9604451977375, 7925.247101525346, 7958.602069896598, 9468.810623787624, 9599.616015359308, 9710.465924374228, 9766.169875560372, 9864.506774661382, 9758.121769040821, 9914.950425247827, 10020.949895250476, 10078.445685487368, 10136.493175341056, 6734.764283250062, 7470.252974702486, 7552.584607846621, 7632.732854350071, 7637.961810191136, 8069.757907262791, 8215.753527394188, 8242.340612750846, 8250.917490825015, 8328.875066874123, 7125.429965602843, 7732.072151341875, 7736.342410895137, 7868.213178682315, 7884.566348850639, 7594.582297973665, 7719.150893401834, 7732.884006740016, 7804.985351904444, 7831.060272767304, 6174.44430001302, 7846.80382990407, 7883.369330453665, 7900.881486777818, 7921.366290696616, 6860.2453730088655, 7041.788746337618, 7059.823504412226, 7061.682224299816, 7157.785266442015, 7092.645367731696, 7115.0, 7155.356017958408, 7162.068931038932, 7170.677319520739], "unit": "score", "name": "mootools.html", "value": 4908.454608826824}, {"lowerIsBetter": false, "replicates": [321.8085239282627, 329.41035546371995, 331.6766153000823, 335.0083752093801, 338.7408632396219, 5866.911996320054, 6187.793380290845, 6864.588124712508, 6951.131108611424, 7033.437325014, 6835.555688880232, 6887.104676392062, 6917.965410172955, 6923.238443771181, 6933.583984960893, 5059.4181669107975, 5064.290999260106, 5076.187809950408, 5080.6028342206, 5087.66930149541, 4138.503379594453, 5050.595952323823, 5066.240063990394, 5075.543201111899, 5104.821331253407, 4785.377900872882, 5050.898982020376, 5059.696418214919, 5066.0, 5071.7717702703285, 5058.570021548191, 5065.1895696688725, 5069.594432445432, 5072.746362681851, 5078.46676099008, 4359.869203923856, 4367.978160109211, 4381.1237752449315, 4388.736675799462, 4393.53867843875, 6497.447716944065, 6602.834929126762, 6711.161104861892, 6713.966430167818, 6733.966330168318, 5948.97025514874, 5953.672548009858, 5954.404559544054, 5958.016927207005, 5969.194158788551, 6319.842003949938, 7391.519551229153, 7406.59263740494, 7422.331990120915, 7438.218987006395, 3786.981065094657, 4135.0, 4780.139574876521, 4851.636127290467, 4862.100511405401, 6261.436470717657, 6313.936860631381, 7921.56431396273, 7969.880451793228, 8019.879701804419, 4942.728149951715, 5012.649114562018, 5016.648834581617, 5017.67385119966, 5036.924446133311, 5026.648134630541, 5138.948610513922, 5165.638405311667, 5171.4311425743135, 5189.76646050921, 5975.850603734942, 6006.249218847644, 6008.729607167688, 6024.367441418588, 6038.879222415527, 7476.140243871915, 7477.102747670315, 7484.176740558534, 7503.2496750324535, 7514.699412023567, 1686.932522699103, 1739.8956062636282, 1759.5601099725068, 1792.6056267621102, 1796.6586348593726, 1123.8819923907872, 1124.6063877642923, 1138.0895283773114, 1139.566964553481, 1142.954281828734, 7045.189803172599, 7097.0, 7108.8933665995055, 7136.714531418789, 7141.928580714126, 7787.961060194776, 7793.10379306387, 7802.531848089133, 7832.334251588565, 7838.0, 6828.658567071627, 7086.397656199178, 7086.92913070873, 7093.680784364716, 7142.357187853118, 4111.650509706648, 4114.19773144234, 4127.4015267786, 4144.979275103605, 4152.626263636287, 8132.024157101186, 8132.390070744599, 8140.267375936194, 8151.79620509492, 8159.877601835978, 6702.631355275409, 7847.568383738835, 8019.558924259221, 8020.598970051474, 8130.471519351223, 6551.967240163769, 8071.5964201789675, 8123.7562873115085, 8129.105798362294, 8160.061592916772, 5512.503874651301, 5581.358143813351, 7078.752243671447, 7462.253774622712, 7742.883856742041, 6429.38920802523, 7613.314801667876, 7642.388608911163, 7701.152873184057, 7736.729214477691, 8163.510189388656, 8228.382871284557, 8233.752987410388, 8241.217084376975, 8246.917530824616, 4653.604443622195, 4801.159797035435, 4801.855944321676, 4821.9035619288115, 4839.685420447659, 7745.612719363897, 7780.610969451618, 7785.416093792875, 7807.258310460497, 7809.1409944907155, 6778.796636100925, 6809.604031173499, 7279.963600182177, 7503.362214211853, 7508.887366689395, 7399.926000739923, 7509.8498030038, 7552.282533159341, 7554.093508778982, 7595.696172153052, 6083.722418292031, 7384.150822655463, 7410.6665200065045, 7411.592362420119, 7418.1839997599145, 7321.377682897013, 7565.621718914143, 7576.469647124648, 7587.848243035219, 7615.65729542183, 7390.926090739239, 7405.148407933157, 7432.070991126109, 7432.21961694036, 7438.404927605887], "unit": "score", "name": "prototype.html", "value": 5400.103749541096}, {"lowerIsBetter": false, "replicates": [689.8930665746811, 716.8028792082179, 731.3052600029973, 741.6106544064365, 744.3933194446525, 14748.115113093208, 14784.0, 14857.702845943075, 14942.5517234483, 14995.850041499581, 13482.697338205637, 13832.93083534581, 13943.233122178277, 13970.580882573538, 13978.440862365518, 709.2411120101493, 858.3004851046393, 870.2994089757742, 876.0757400942003, 893.3433926064338, 872.2498651159997, 881.7575166829109, 882.9381943263974, 889.6797153024905, 898.6225785170244, 916.463868636845, 916.8533034714447, 931.9655182747146, 936.7002559181064, 937.4515908193681, 891.2468963725642, 928.6703220356744, 930.2790337488467, 930.6649606141783, 931.1293940165932, 681.2200030964567, 687.2337343861608, 687.4534744877834, 692.6155983429195, 693.2547511425238, 14325.283735813167, 14340.856591434056, 14390.856091439056, 14438.639034024129, 14468.565943021726, 12770.87229127706, 12886.67783305425, 12896.935515322364, 13083.476660933551, 13192.53826116091, 10048.397096174253, 10280.331778434378, 10293.245338131797, 10306.278560500694, 10650.786984260349, 3515.6308587598446, 3548.3258180945795, 3550.9822450887837, 3552.378333791576, 3555.6266592007723, 65363.0, 68538.0, 71241.57516849686, 71246.9312960306, 71361.28638713599, 9227.677031303872, 9290.62837486493, 9301.162895339452, 9469.573869175903, 9848.753781155385, 19911.60176796456, 19931.202751889763, 19931.900340498203, 19974.30089946845, 19986.400407987785, 7173.89239161413, 7399.0, 7414.814629634302, 7531.209223031612, 7612.429067819936, 4180.7909604519655, 4199.832006719697, 4214.810333534998, 4250.022494826208, 4250.723702959298, 3489.808060556643, 3514.9982255057184, 3535.310614430213, 3538.5046093546925, 3553.591336996227, 594.4382558482192, 595.553334998751, 599.2090440618429, 604.7641419846263, 626.946709529686, 527.860117068977, 543.9401665816757, 563.0934195944526, 602.6805792929754, 623.8783437229697, 8025.638846251816, 8134.59327033646, 8463.49219046859, 8800.559972001374, 8881.777955551033, 455.80628233001505, 519.7271432497894, 545.912653975362, 580.9099589563624, 610.8656095658948, 3481.742568721249, 3979.263836190314, 4030.274550580865, 4252.936205956913, 4279.2083464559155, 85702.14297857066, 87586.56206718925, 88768.5561572188, 90001.54999224962, 90300.09699902916, 67755.66122169457, 68090.65954670294, 70882.9367559487, 76740.0, 77480.61259693778, 53494.0, 72455.63772181, 72767.63616181885, 72895.0, 73072.17319567053, 7497.437692173, 7985.72049978248, 8733.039365669898, 8944.239739621944, 8975.416597921114, 49806.003879921474, 50244.4926652201, 50815.49184508107, 51168.0, 51216.975660487326, 64743.70512589816, 65875.35311617248, 66589.0, 68105.97841032289, 68851.62296754136, 10524.842127368249, 10794.676159715222, 10805.45972701346, 10899.182561308093, 11213.159013074215, 11616.361100139573, 11911.225770324902, 12020.459079341626, 12065.939670301592, 12131.636050918487, 11085.224034317844, 11213.775724485628, 11612.12909031809, 11621.0, 11651.666018798398, 11661.24201926872, 11714.648560543197, 11834.467448964646, 11942.641720748044, 11944.343061131722, 11314.660560183207, 11587.536498539965, 11651.650450486499, 11749.236299640495, 11812.527498900292, 9132.634694612141, 9249.121333473578, 9249.260059195114, 9427.575759090994, 9566.473843938371, 7916.445848790529, 9710.22318214527, 9879.209663227068, 9935.751606209902, 9937.155341795738], "unit": "score", "name": "yui.html", "value": 6800.520479823865}], "name": "dromaeo_css", "value": 7629.900859304987}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 116.0, 167.0, 91.0, 111.0, 126.0, 112.0, 114.0, 115.0, 114.0], "unit": "score", "name": "ai-astar", "value": 118.2}, {"lowerIsBetter": true, "replicates": [170.0, 111.0, 178.0, 176.0, 149.0, 143.0, 153.0, 106.0, 184.0, 151.0], "unit": "score", "name": "audio-beat-detection", "value": 152.1}, {"lowerIsBetter": true, "replicates": [202.0, 192.0, 201.0, 218.0, 231.0, 196.0, 193.0, 198.0, 245.0, 223.0], "unit": "score", "name": "audio-dft", "value": 209.9}, {"lowerIsBetter": true, "replicates": [72.0, 75.0, 73.0, 128.0, 78.0, 58.0, 71.0, 85.0, 143.0, 73.0], "unit": "score", "name": "audio-fft", "value": 85.6}, {"lowerIsBetter": true, "replicates": [82.0, 85.0, 131.0, 78.0, 80.0, 81.0, 117.0, 79.0, 82.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 93.4}, {"lowerIsBetter": true, "replicates": [98.0, 140.0, 85.0, 92.0, 87.0, 113.0, 77.0, 140.0, 131.0, 94.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 105.7}, {"lowerIsBetter": true, "replicates": [99.0, 96.0, 148.0, 149.0, 95.0, 119.0, 111.0, 96.0, 98.0, 96.0], "unit": "score", "name": "imaging-darkroom", "value": 110.7}, {"lowerIsBetter": true, "replicates": [151.0, 102.0, 113.0, 121.0, 98.0, 97.0, 99.0, 95.0, 116.0, 150.0], "unit": "score", "name": "imaging-desaturate", "value": 114.2}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 55.0, 56.0, 55.0, 57.0, 54.0, 65.0, 55.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 70.0, 71.0, 71.0, 68.0, 67.0, 64.0, 65.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [87.0, 78.0, 98.0, 122.0, 116.0, 115.0, 86.0, 116.0, 82.0, 106.0], "unit": "score", "name": "stanford-crypto-aes", "value": 100.6}, {"lowerIsBetter": true, "replicates": [134.0, 129.0, 124.0, 127.0, 148.0, 140.0, 141.0, 126.0, 172.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 137.4}, {"lowerIsBetter": true, "replicates": [142.0, 139.0, 141.0, 143.0, 183.0, 145.0, 142.0, 142.0, 152.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.8}, {"lowerIsBetter": true, "replicates": [69.0, 84.0, 96.0, 61.0, 88.0, 98.0, 99.0, 69.0, 65.0, 71.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 80.0}], "name": "kraken", "value": 1578.5000000000002}]} 05:30:21 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,61]]", "result": "1578.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,61]]", "result": "7629.90"}}} 05:30:21 INFO - Return code: 0 05:30:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:30:21 INFO - # TBPL SUCCESS # 05:30:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:21 INFO - 2016-02-06 05:30:21.527 screenresolution[2745:33485] starting screenresolution argv=screenresolution get 05:30:21 INFO - 2016-02-06 05:30:21.529 screenresolution[2745:33485] Display 0: 1600x1200x32@60 05:30:21 INFO - 2016-02-06 05:30:21.535 screenresolution[2746:33487] starting screenresolution argv=screenresolution list 05:30:21 INFO - Available Modes on Display 0 05:30:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:21 INFO - Intel Iris: 05:30:21 INFO - Chipset Model: Intel Iris 05:30:21 INFO - Type: GPU 05:30:21 INFO - Bus: Built-In 05:30:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:21 INFO - Vendor: Intel (0x8086) 05:30:21 INFO - Device ID: 0x0a2e 05:30:21 INFO - Revision ID: 0x0009 05:30:21 INFO - Displays: 05:30:21 INFO - Display: 05:30:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:21 INFO - Display Serial Number: 1600x1200 60 05:30:21 INFO - Main Display: Yes 05:30:21 INFO - Mirror: Off 05:30:21 INFO - Online: Yes 05:30:21 INFO - Rotation: Supported 05:30:21 INFO - Return code: 0 05:30:21 INFO - Running post-action listener: _resource_record_post_action 05:30:21 INFO - Running post-run listener: _resource_record_post_run 05:30:22 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 925696; Write bytes: 712103424; Read time: 9; Write time: 2444 05:30:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397261312; Write bytes: 391173632; Read time: 29815; Write time: 1183 05:30:22 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 925696; Write bytes: 303095808; Read time: 9; Write time: 1221 05:30:22 INFO - Running post-run listener: _upload_blobber_files 05:30:22 INFO - Blob upload gear active. 05:30:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:30:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:30:23 INFO - (blobuploader) - INFO - Open directory for files ... 05:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:24 INFO - (blobuploader) - INFO - Done attempting. 05:30:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:30:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:24 INFO - (blobuploader) - INFO - Done attempting. 05:30:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:30:24 INFO - Return code: 0 05:30:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:30:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:30:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2037a16e5a77aa61f50524c61e513f5afd37b90b73430ba6e7ec2cee0abb53563d034b96257add7824d69d810f9a9cbf2cdadeac073f6698fcc8362cacc81c00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4532b769d03ac83599d96a3235d860e99c50a3f85522451af5f58e753e34eb7c770e1dd4444ed56f69e636a7e80a47b3d70b53b02c49fff8e1200e7ac6dc3716"} 05:30:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:30:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:30:24 INFO - Contents: 05:30:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2037a16e5a77aa61f50524c61e513f5afd37b90b73430ba6e7ec2cee0abb53563d034b96257add7824d69d810f9a9cbf2cdadeac073f6698fcc8362cacc81c00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4532b769d03ac83599d96a3235d860e99c50a3f85522451af5f58e753e34eb7c770e1dd4444ed56f69e636a7e80a47b3d70b53b02c49fff8e1200e7ac6dc3716"} 05:30:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:30:24 INFO - Copying logs to upload dir... 05:30:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:30:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.656088 ========= master_lag: 7.71 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-02-06 05:30:32.726042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:30:32.729920) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2037a16e5a77aa61f50524c61e513f5afd37b90b73430ba6e7ec2cee0abb53563d034b96257add7824d69d810f9a9cbf2cdadeac073f6698fcc8362cacc81c00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4532b769d03ac83599d96a3235d860e99c50a3f85522451af5f58e753e34eb7c770e1dd4444ed56f69e636a7e80a47b3d70b53b02c49fff8e1200e7ac6dc3716"} build_url:https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/SZug8lhjT8WIDkDcm6dGpw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2037a16e5a77aa61f50524c61e513f5afd37b90b73430ba6e7ec2cee0abb53563d034b96257add7824d69d810f9a9cbf2cdadeac073f6698fcc8362cacc81c00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4532b769d03ac83599d96a3235d860e99c50a3f85522451af5f58e753e34eb7c770e1dd4444ed56f69e636a7e80a47b3d70b53b02c49fff8e1200e7ac6dc3716"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:30:32.793009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:30:32.793330) ========= 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.kSy2G5Ay6M/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.zacNHzEg1g/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005326 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:30:32.842037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:30:32.842358) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:30:32.842710) ========= ========= Total master_lag: 8.60 =========