builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0122 starttime: 1460030717.35 results: success (0) buildid: 20160407044321 builduid: ba1ed4375a6d4bae984201d98aa2f4c5 revision: 2355485d9a8e09c7cfe47063bd192b72ec34d286 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.347072) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.348014) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.348651) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005490 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.373005) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.373288) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.389829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.390105) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005537 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.441509) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.442256) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.443017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.443720) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-07 05:05:17-- 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% 33.2M=0s 2016-04-07 05:05:17 (33.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110576 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.568327) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.568637) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.019128 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:17.619366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:17.619760) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-07 05:05:17,693 truncating revision to first 12 chars 2016-04-07 05:05:17,693 Setting DEBUG logging. 2016-04-07 05:05:17,694 attempt 1/10 2016-04-07 05:05:17,694 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2355485d9a8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 05:05:18,511 unpacking tar archive at: mozilla-aurora-2355485d9a8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.067566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:18.705942) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:18.706349) ========= echo 2355485d9a8e09c7cfe47063bd192b72ec34d286 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'2355485d9a8e09c7cfe47063bd192b72ec34d286'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 2355485d9a8e09c7cfe47063bd192b72ec34d286 program finished with exit code 0 elapsedTime=0.005108 script_repo_revision: '2355485d9a8e09c7cfe47063bd192b72ec34d286' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:18.758404) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:18.758757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:18.773583) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 05:05:18.774055) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:18 INFO - MultiFileLogger online at 20160407 05:05:18 in /builds/slave/test 05:05:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:05:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:18 INFO - {'append_to_log': False, 05:05:18 INFO - 'base_work_dir': '/builds/slave/test', 05:05:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:18 INFO - 'branch': 'Mozilla-Aurora', 05:05:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:18 INFO - 'config_files': ('talos/mac_config.py',), 05:05:18 INFO - 'default_actions': ('clobber', 05:05:18 INFO - 'read-buildbot-config', 05:05:18 INFO - 'download-and-extract', 05:05:18 INFO - 'populate-webroot', 05:05:18 INFO - 'create-virtualenv', 05:05:18 INFO - 'install', 05:05:18 INFO - 'run-tests'), 05:05:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:18 INFO - 'download_minidump_stackwalk': True, 05:05:18 INFO - 'download_symbols': 'ondemand', 05:05:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:18 INFO - 'installer_path': 'installer.exe', 05:05:18 INFO - 'log_level': 'info', 05:05:18 INFO - 'log_name': 'talos', 05:05:18 INFO - 'log_to_console': True, 05:05:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:18 INFO - 'opt_config_files': (), 05:05:18 INFO - 'pip_index': False, 05:05:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:18 INFO - 'cmd': ('bash', 05:05:18 INFO - '-c', 05:05:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:18 INFO - 'enabled': True, 05:05:18 INFO - 'halt_on_failure': False, 05:05:18 INFO - 'name': 'check_screen_resolution'},), 05:05:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:18 INFO - 'cmd': ('bash', 05:05:18 INFO - '-c', 05:05:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:18 INFO - 'enabled': True, 05:05:18 INFO - 'halt_on_failure': False, 05:05:18 INFO - 'name': 'check_screen_resolution'},), 05:05:18 INFO - 'run_cmd_checks_enabled': True, 05:05:18 INFO - 'sps_profile': False, 05:05:18 INFO - 'sps_profile_interval': 0, 05:05:18 INFO - 'suite': 'dromaeojs', 05:05:18 INFO - 'system_bits': '32', 05:05:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:18 INFO - 'title': 't-yosemite-r7-0122', 05:05:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:18 INFO - 'use_talos_json': True, 05:05:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:18 INFO - 'work_dir': 'build'} 05:05:18 INFO - ##### 05:05:18 INFO - ##### Running clobber step. 05:05:18 INFO - ##### 05:05:18 INFO - Running pre-action listener: _resource_record_pre_action 05:05:18 INFO - Running main action method: clobber 05:05:18 INFO - rmtree: /builds/slave/test/build 05:05:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:20 INFO - Running post-action listener: _resource_record_post_action 05:05:20 INFO - ##### 05:05:20 INFO - ##### Running read-buildbot-config step. 05:05:20 INFO - ##### 05:05:20 INFO - Running pre-action listener: _resource_record_pre_action 05:05:20 INFO - Running main action method: read_buildbot_config 05:05:20 INFO - Using buildbot properties: 05:05:20 INFO - { 05:05:20 INFO - "project": "", 05:05:20 INFO - "product": "firefox", 05:05:20 INFO - "script_repo_revision": "production", 05:05:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:05:20 INFO - "repository": "", 05:05:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:05:20 INFO - "buildid": "20160407044321", 05:05:20 INFO - "stage_platform": "macosx64", 05:05:20 INFO - "basedir": "/builds/slave/test", 05:05:20 INFO - "buildnumber": 162, 05:05:20 INFO - "platform": "yosemite_r7", 05:05:20 INFO - "mozci_request": { 05:05:20 INFO - "priority": -1, 05:05:20 INFO - "type": "trigger_all_talos_jobs", 05:05:20 INFO - "times": 6 05:05:20 INFO - }, 05:05:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:05:20 INFO - "slavebuilddir": "test", 05:05:20 INFO - "branch": "mozilla-aurora", 05:05:20 INFO - "revision": "2355485d9a8e09c7cfe47063bd192b72ec34d286", 05:05:20 INFO - "slavename": "t-yosemite-r7-0122", 05:05:20 INFO - "builduid": "ba1ed4375a6d4bae984201d98aa2f4c5", 05:05:20 INFO - "repo_path": "releases/mozilla-aurora" 05:05:20 INFO - } 05:05:20 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg. 05:05:20 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json. 05:05:20 INFO - Running post-action listener: _resource_record_post_action 05:05:20 INFO - ##### 05:05:20 INFO - ##### Running download-and-extract step. 05:05:20 INFO - ##### 05:05:20 INFO - Running pre-action listener: _resource_record_pre_action 05:05:20 INFO - Running main action method: download_and_extract 05:05:20 INFO - mkdir: /builds/slave/test/build/tests 05:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json 05:05:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 05:05:21 INFO - Downloaded 1277 bytes. 05:05:21 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:21 INFO - Using the following test package requirements: 05:05:21 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:21 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 05:05:21 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 05:05:21 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'jsshell-mac.zip'], 05:05:21 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 05:05:21 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:21 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 05:05:21 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 05:05:21 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 05:05:21 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:21 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:21 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 05:05:21 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:05: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:05:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:21 INFO - Downloaded 17904082 bytes. 05:05:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:24 INFO - Return code: 0 05:05:24 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:05:24 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:05:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:25 INFO - Downloaded 17904082 bytes. 05:05:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:28 INFO - Return code: 0 05:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip 05:05:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 05:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:05:28 INFO - Downloaded 10845168 bytes. 05:05:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:05:29 INFO - Return code: 0 05:05:29 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:05:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 05:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 05:05:33 INFO - Downloaded 115185382 bytes. 05:05:33 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:33 INFO - mkdir: /builds/slave/test/properties 05:05:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:33 INFO - Contents: 05:05:33 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:33 INFO - Running post-action listener: _resource_record_post_action 05:05:33 INFO - Running post-action listener: set_extra_try_arguments 05:05:33 INFO - ##### 05:05:33 INFO - ##### Running populate-webroot step. 05:05:33 INFO - ##### 05:05:33 INFO - Running pre-action listener: _resource_record_pre_action 05:05:33 INFO - Running main action method: populate_webroot 05:05:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:33 INFO - '--tppagecycles', 05:05:33 INFO - '7'], 05:05:33 INFO - 'tests': ['tsvgm']}}, 05:05:33 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:05:33 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:33 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:05:33 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['damp', 'tps']}, 05:05:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['damp', 'tps']}, 05:05:33 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:05:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:33 INFO - 'other': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other_l64': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other_nol64': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'svgr': {'tests': ['tsvgx', 05:05:33 INFO - 'tsvgr_opacity', 05:05:33 INFO - 'tart', 05:05:33 INFO - 'tscrollx', 05:05:33 INFO - 'cart']}, 05:05:33 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['tsvgx', 05:05:33 INFO - 'tsvgr_opacity', 05:05:33 INFO - 'tart', 05:05:33 INFO - 'tscrollx', 05:05:33 INFO - 'cart']}, 05:05:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['tp5o']}, 05:05:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--e10s'], 05:05:33 INFO - 'tests': ['tp5o']}, 05:05:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--xperf_path', 05:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:33 INFO - 'tests': ['tp5n']}, 05:05:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--e10s', 05:05:33 INFO - '--xperf_path', 05:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:33 INFO - 'tests': ['tp5n']}}, 05:05:33 INFO - 'talos.zip': {'path': '', 05:05:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:33 INFO - Running post-action listener: _resource_record_post_action 05:05:33 INFO - ##### 05:05:33 INFO - ##### Running create-virtualenv step. 05:05:33 INFO - ##### 05:05:33 INFO - Running pre-action listener: _resource_record_pre_action 05:05:33 INFO - Running main action method: create_virtualenv 05:05:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:33 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:05:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:33 INFO - Using real prefix '/tools/python27' 05:05:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:34 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:36 INFO - Installing pip.................done. 05:05:36 INFO - Return code: 0 05:05:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05: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:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:36 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:05:36 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:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:36 INFO - 'HOME': '/Users/cltbld', 05:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:36 INFO - 'LOGNAME': 'cltbld', 05:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:36 INFO - 'MOZ_NO_REMOTE': '1', 05:05:36 INFO - 'NO_EM_RESTART': '1', 05:05:36 INFO - 'PAGER': '/bin/cat', 05:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:36 INFO - 'PWD': '/builds/slave/test', 05:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:36 INFO - 'SHELL': '/bin/bash', 05:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:36 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:36 INFO - 'USER': 'cltbld', 05:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:36 INFO - 'XPC_FLAGS': '0x0', 05:05:36 INFO - 'XPC_SERVICE_NAME': '0', 05:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 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:05:37 INFO - Cleaning up... 05:05:37 INFO - Return code: 0 05:05:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:05: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:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:37 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:05:37 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:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:37 INFO - 'HOME': '/Users/cltbld', 05:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:37 INFO - 'LOGNAME': 'cltbld', 05:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:37 INFO - 'MOZ_NO_REMOTE': '1', 05:05:37 INFO - 'NO_EM_RESTART': '1', 05:05:37 INFO - 'PAGER': '/bin/cat', 05:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:37 INFO - 'PWD': '/builds/slave/test', 05:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:37 INFO - 'SHELL': '/bin/bash', 05:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:37 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:37 INFO - 'USER': 'cltbld', 05:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:37 INFO - 'XPC_FLAGS': '0x0', 05:05:37 INFO - 'XPC_SERVICE_NAME': '0', 05:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 INFO - Downloading/unpacking psutil>=0.7.1 05:05:37 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:05:37 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:05:37 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:05:37 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:05:37 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:05:37 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:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:40 INFO - Installing collected packages: psutil 05:05:40 INFO - Running setup.py install for psutil 05:05:41 INFO - building 'psutil._psutil_osx' extension 05:05:41 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:05:41 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:05:41 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:05:41 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:05:41 INFO - building 'psutil._psutil_posix' extension 05:05:41 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:05:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:41 INFO - ^ 05:05:41 INFO - 1 warning generated. 05:05:41 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:05:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:41 INFO - Successfully installed psutil 05:05:41 INFO - Cleaning up... 05:05:41 INFO - Return code: 0 05:05:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:05:41 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:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:41 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:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:41 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:41 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:05:41 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:05:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:41 INFO - 'HOME': '/Users/cltbld', 05:05:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:41 INFO - 'LOGNAME': 'cltbld', 05:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:41 INFO - 'MOZ_NO_REMOTE': '1', 05:05:41 INFO - 'NO_EM_RESTART': '1', 05:05:41 INFO - 'PAGER': '/bin/cat', 05:05:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:41 INFO - 'PWD': '/builds/slave/test', 05:05:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:41 INFO - 'SHELL': '/bin/bash', 05:05:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:41 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:41 INFO - 'USER': 'cltbld', 05:05:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:41 INFO - 'XPC_FLAGS': '0x0', 05:05:41 INFO - 'XPC_SERVICE_NAME': '0', 05:05:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:44 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:05:44 INFO - Installing collected packages: mozsystemmonitor 05:05:44 INFO - Running setup.py install for mozsystemmonitor 05:05:44 INFO - Successfully installed mozsystemmonitor 05:05:44 INFO - Cleaning up... 05:05:44 INFO - Return code: 0 05:05:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:44 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:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 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:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:44 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:05:44 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:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:44 INFO - 'HOME': '/Users/cltbld', 05:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:44 INFO - 'LOGNAME': 'cltbld', 05:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:44 INFO - 'MOZ_NO_REMOTE': '1', 05:05:44 INFO - 'NO_EM_RESTART': '1', 05:05:44 INFO - 'PAGER': '/bin/cat', 05:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:44 INFO - 'PWD': '/builds/slave/test', 05:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:44 INFO - 'SHELL': '/bin/bash', 05:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:44 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:44 INFO - 'USER': 'cltbld', 05:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:44 INFO - 'XPC_FLAGS': '0x0', 05:05:44 INFO - 'XPC_SERVICE_NAME': '0', 05:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:05: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:05: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:05: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:05: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:05: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:05: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:05:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:48 INFO - Downloading docopt-0.6.1.tar.gz 05:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:48 INFO - Running setup.py install for blobuploader 05:05:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:48 INFO - Running setup.py install for requests 05:05:49 INFO - Running setup.py install for docopt 05:05:49 INFO - Successfully installed blobuploader requests docopt 05:05:49 INFO - Cleaning up... 05:05:49 INFO - Return code: 0 05:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:49 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:49 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:05:49 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:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:49 INFO - 'HOME': '/Users/cltbld', 05:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:49 INFO - 'LOGNAME': 'cltbld', 05:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:49 INFO - 'MOZ_NO_REMOTE': '1', 05:05:49 INFO - 'NO_EM_RESTART': '1', 05:05:49 INFO - 'PAGER': '/bin/cat', 05:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:49 INFO - 'PWD': '/builds/slave/test', 05:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:49 INFO - 'SHELL': '/bin/bash', 05:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:49 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:49 INFO - 'USER': 'cltbld', 05:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:49 INFO - 'XPC_FLAGS': '0x0', 05:05:49 INFO - 'XPC_SERVICE_NAME': '0', 05:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ngpcuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-0ngB99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Fdbo3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-GcEahs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-14KFWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-rOQJz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-nB8PMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Mu60gI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-D7cC3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-QVORFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ySM5EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-kebPxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-O9nDjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-tYiCdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Tu4sgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-97qVYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ERdZIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:51 INFO - Running setup.py install for manifestparser 05:05:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:51 INFO - Running setup.py install for mozcrash 05:05:51 INFO - Running setup.py install for mozdebug 05:05:52 INFO - Running setup.py install for mozdevice 05:05:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozfile 05:05:52 INFO - Running setup.py install for mozhttpd 05:05:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozinfo 05:05:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozInstall 05:05:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozleak 05:05:52 INFO - Running setup.py install for mozlog 05:05:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for moznetwork 05:05:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozprocess 05:05:53 INFO - Running setup.py install for mozprofile 05:05:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozrunner 05:05:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozscreenshot 05:05:53 INFO - Running setup.py install for moztest 05:05:54 INFO - Running setup.py install for mozversion 05:05:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:54 INFO - Cleaning up... 05:05:54 INFO - Return code: 0 05:05:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:54 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:05:54 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:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:54 INFO - 'HOME': '/Users/cltbld', 05:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:54 INFO - 'LOGNAME': 'cltbld', 05:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:54 INFO - 'MOZ_NO_REMOTE': '1', 05:05:54 INFO - 'NO_EM_RESTART': '1', 05:05:54 INFO - 'PAGER': '/bin/cat', 05:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:54 INFO - 'PWD': '/builds/slave/test', 05:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:54 INFO - 'SHELL': '/bin/bash', 05:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:54 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:54 INFO - 'USER': 'cltbld', 05:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:54 INFO - 'XPC_FLAGS': '0x0', 05:05:54 INFO - 'XPC_SERVICE_NAME': '0', 05:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-JOXqx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:54 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:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ou14Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fsT6IV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:54 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:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5TxUNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:54 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:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-UQ94Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:54 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:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-dc4FLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-CV1eDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PLg9JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-kj1hdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xQPx05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-v1w65Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-sBwjsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-aZxLfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-i9pYsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-NZmOTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:56 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ap5I6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:56 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-caePC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:56 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:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:56 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:05:56 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:05:56 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:05:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:58 INFO - Downloading blessings-1.6.tar.gz 05:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:58 INFO - Installing collected packages: blessings 05:05:58 INFO - Running setup.py install for blessings 05:05:58 INFO - Successfully installed blessings 05:05:58 INFO - Cleaning up... 05:05:59 INFO - Return code: 0 05:05:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:59 INFO - Reading from file tmpfile_stdout 05:05:59 INFO - Current package versions: 05:05:59 INFO - blessings == 1.6 05:05:59 INFO - blobuploader == 1.2.4 05:05:59 INFO - docopt == 0.6.1 05:05:59 INFO - manifestparser == 1.1 05:05:59 INFO - mozInstall == 1.12 05:05:59 INFO - mozcrash == 0.17 05:05:59 INFO - mozdebug == 0.1 05:05:59 INFO - mozdevice == 0.48 05:05:59 INFO - mozfile == 1.2 05:05:59 INFO - mozhttpd == 0.7 05:05:59 INFO - mozinfo == 0.9 05:05:59 INFO - mozleak == 0.1 05:05:59 INFO - mozlog == 3.1 05:05:59 INFO - moznetwork == 0.27 05:05:59 INFO - mozprocess == 0.22 05:05:59 INFO - mozprofile == 0.28 05:05:59 INFO - mozrunner == 6.11 05:05:59 INFO - mozscreenshot == 0.1 05:05:59 INFO - mozsystemmonitor == 0.0 05:05:59 INFO - moztest == 0.7 05:05:59 INFO - mozversion == 1.4 05:05:59 INFO - psutil == 3.1.1 05:05:59 INFO - requests == 1.2.3 05:05:59 INFO - wsgiref == 0.1.2 05:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:59 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:05:59 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:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:59 INFO - 'HOME': '/Users/cltbld', 05:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:59 INFO - 'LOGNAME': 'cltbld', 05:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:59 INFO - 'MOZ_NO_REMOTE': '1', 05:05:59 INFO - 'NO_EM_RESTART': '1', 05:05:59 INFO - 'PAGER': '/bin/cat', 05:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:59 INFO - 'PWD': '/builds/slave/test', 05:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:59 INFO - 'SHELL': '/bin/bash', 05:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:59 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:59 INFO - 'USER': 'cltbld', 05:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:59 INFO - 'XPC_FLAGS': '0x0', 05:05:59 INFO - 'XPC_SERVICE_NAME': '0', 05:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 INFO - Cleaning up... 05:05:59 INFO - Return code: 0 05:05:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:05:59 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:05:59 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:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:59 INFO - 'HOME': '/Users/cltbld', 05:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:59 INFO - 'LOGNAME': 'cltbld', 05:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:59 INFO - 'MOZ_NO_REMOTE': '1', 05:05:59 INFO - 'NO_EM_RESTART': '1', 05:05:59 INFO - 'PAGER': '/bin/cat', 05:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:59 INFO - 'PWD': '/builds/slave/test', 05:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:59 INFO - 'SHELL': '/bin/bash', 05:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:05:59 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:59 INFO - 'USER': 'cltbld', 05:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:59 INFO - 'XPC_FLAGS': '0x0', 05:05:59 INFO - 'XPC_SERVICE_NAME': '0', 05:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:59 INFO - Downloading/unpacking jsonschema 05:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:06:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:02 INFO - Downloading/unpacking functools32 (from jsonschema) 05:06:02 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:06:02 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:06:02 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:06:02 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:06:02 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:06:02 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:06:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:02 INFO - warning: no files found matching '*.txt' 05:06:02 INFO - no previously-included directories found matching 'build' 05:06:02 INFO - no previously-included directories found matching 'dist' 05:06:02 INFO - no previously-included directories found matching '.git*' 05:06:02 INFO - Installing collected packages: jsonschema, functools32 05:06:02 INFO - Running setup.py install for functools32 05:06:02 INFO - warning: no files found matching '*.txt' 05:06:02 INFO - no previously-included directories found matching 'build' 05:06:02 INFO - no previously-included directories found matching 'dist' 05:06:02 INFO - no previously-included directories found matching '.git*' 05:06:02 INFO - Successfully installed jsonschema functools32 05:06:02 INFO - Cleaning up... 05:06:02 INFO - Return code: 0 05:06:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:02 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 0x109d07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8b9dc08b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.E7M7FXzozx/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.O8QrRFyB65/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:06:02 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:06:02 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:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:02 INFO - 'HOME': '/Users/cltbld', 05:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:02 INFO - 'LOGNAME': 'cltbld', 05:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:02 INFO - 'MOZ_NO_REMOTE': '1', 05:06:02 INFO - 'NO_EM_RESTART': '1', 05:06:02 INFO - 'PAGER': '/bin/cat', 05:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:02 INFO - 'PWD': '/builds/slave/test', 05:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:02 INFO - 'SHELL': '/bin/bash', 05:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:06:02 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:02 INFO - 'USER': 'cltbld', 05:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:02 INFO - 'XPC_FLAGS': '0x0', 05:06:02 INFO - 'XPC_SERVICE_NAME': '0', 05:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:03 INFO - Downloading/unpacking flake8 05:06: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:06: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:06: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:06: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:06: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:06: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:06:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:06 INFO - no previously-included directories found matching 'docs/_build' 05:06:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:07 INFO - Running setup.py install for pep8 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:07 INFO - no previously-included directories found matching 'docs/_build' 05:06:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:06:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:07 INFO - Cleaning up... 05:06:07 INFO - Return code: 0 05:06:07 INFO - Running post-action listener: _resource_record_post_action 05:06:07 INFO - Running post-action listener: _start_resource_monitoring 05:06:07 INFO - Starting resource monitoring. 05:06:07 INFO - ##### 05:06:07 INFO - ##### Running install step. 05:06:07 INFO - ##### 05:06:07 INFO - Running pre-action listener: _resource_record_pre_action 05:06:07 INFO - Running main action method: install 05:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:07 INFO - Reading from file tmpfile_stdout 05:06:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:07 INFO - Reading from file tmpfile_stdout 05:06:07 INFO - Output received: 05:06:07 INFO - Usage: mozinstall [options] installer 05:06:07 INFO - Options: 05:06:07 INFO - -h, --help show this help message and exit 05:06:07 INFO - -d DEST, --destination=DEST 05:06:07 INFO - Directory to install application into. [default: 05:06:07 INFO - "/builds/slave/test"] 05:06:07 INFO - --app=APP Application being installed. [default: firefox] 05:06:07 INFO - mkdir: /builds/slave/test/build/application 05:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:06:39 INFO - Reading from file tmpfile_stdout 05:06:39 INFO - Output received: 05:06:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:06:39 INFO - Running post-action listener: _resource_record_post_action 05:06:39 INFO - ##### 05:06:39 INFO - ##### Running run-tests step. 05:06:39 INFO - ##### 05:06:39 INFO - Running pre-action listener: _resource_record_pre_action 05:06:39 INFO - Running main action method: run_tests 05:06:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:39 INFO - 2016-04-07 05:06:39.507 screenresolution[1938:18476] starting screenresolution argv=screenresolution get 05:06:39 INFO - 2016-04-07 05:06:39.510 screenresolution[1938:18476] Display 0: 1600x1200x32@60 05:06:39 INFO - 2016-04-07 05:06:39.515 screenresolution[1939:18478] starting screenresolution argv=screenresolution list 05:06:39 INFO - Available Modes on Display 0 05:06:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:39 INFO - Intel Iris: 05:06:39 INFO - Chipset Model: Intel Iris 05:06:39 INFO - Type: GPU 05:06:39 INFO - Bus: Built-In 05:06:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:39 INFO - Vendor: Intel (0x8086) 05:06:39 INFO - Device ID: 0x0a2e 05:06:39 INFO - Revision ID: 0x0009 05:06:39 INFO - Displays: 05:06:39 INFO - Display: 05:06:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:39 INFO - Display Serial Number: 1600x1200 60 05:06:39 INFO - Main Display: Yes 05:06:39 INFO - Mirror: Off 05:06:39 INFO - Online: Yes 05:06:39 INFO - Rotation: Supported 05:06:39 INFO - Return code: 0 05:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:39 INFO - Python 2.7.3 05:06:39 INFO - Return code: 0 05:06:39 INFO - grabbing minidump binary from tooltool 05:06:39 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:06:39 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 0x109babd30>, '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 0x109a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:39 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:06:39 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:06:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:39 INFO - Return code: 0 05:06:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:06:41 INFO - Return code: 0 05:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E7M7FXzozx/Render', 05:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:41 INFO - 'HOME': '/Users/cltbld', 05:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:41 INFO - 'LOGNAME': 'cltbld', 05:06:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:41 INFO - 'MOZ_NO_REMOTE': '1', 05:06:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:41 INFO - 'NO_EM_RESTART': '1', 05:06:41 INFO - 'PAGER': '/bin/cat', 05:06:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:41 INFO - 'PWD': '/builds/slave/test', 05:06:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:41 INFO - 'SHELL': '/bin/bash', 05:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8QrRFyB65/Listeners', 05:06:41 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:41 INFO - 'USER': 'cltbld', 05:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:41 INFO - 'XPC_FLAGS': '0x0', 05:06:41 INFO - 'XPC_SERVICE_NAME': '0', 05:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:06:41 INFO - mozversion application_buildid: 20160406205952 05:06:41 INFO - mozversion application_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:06:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:06:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:41 INFO - mozversion application_name: Firefox 05:06:41 INFO - mozversion application_remotingname: firefox-dev 05:06:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:41 INFO - mozversion application_vendor: Mozilla 05:06:41 INFO - mozversion application_version: 47.0a2 05:06:41 INFO - mozversion platform_buildid: 20160406205952 05:06:41 INFO - mozversion platform_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:06:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:41 INFO - mozversion platform_version: 47.0a2 05:06:41 INFO - using testdate: 1460030801 05:06:41 INFO - actual date: 1460030801 05:06:41 INFO - starting webserver on 'localhost:57283' 05:06:41 INFO - SUITE-START | Running 2 tests 05:06:41 INFO - TEST-START | dromaeo_css 05:06:41 INFO - operating with platform_type : mac_ 05:06:41 INFO - Initialising browser for dromaeo_css test... 05:06:41 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpY0GrFj/profile http://localhost:57283/getInfo.html) 05:06:43 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 05:06:43 INFO - PROCESS | 1957 | colorDepth:24 05:06:43 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 05:06:43 INFO - PROCESS | 1957 | __metrics 05:06:43 INFO - PROCESS | 1957 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:06:43 INFO - PROCESS | 1957 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:44 INFO - TEST-INFO | 1957: exit 0 05:06:44 INFO - Browser initialized. 05:06:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:44 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpY0GrFj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:56 INFO - PROCESS | 1959 | RSS: Main: 207208448 05:06:56 INFO - PROCESS | 1959 | 05:07:46 INFO - PROCESS | 1959 | 1460030866792 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:09:56 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57283/tests/dromaeo/cssquery-ext.html) 05:09:58 INFO - PROCESS | 1959 | RSS: Main: 344350720 05:09:58 INFO - PROCESS | 1959 | 05:13:00 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-ext.html (next: http://localhost:57283/tests/dromaeo/cssquery-jquery.html) 05:13:01 INFO - PROCESS | 1959 | RSS: Main: 346263552 05:13:01 INFO - PROCESS | 1959 | 05:16:03 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57283/tests/dromaeo/cssquery-mootools.html) 05:16:03 INFO - PROCESS | 1959 | RSS: Main: 345853952 05:16:03 INFO - PROCESS | 1959 | 05:19:05 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57283/tests/dromaeo/cssquery-prototype.html) 05:19:06 INFO - PROCESS | 1959 | RSS: Main: 305823744 05:19:06 INFO - PROCESS | 1959 | 05:22:09 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57283/tests/dromaeo/cssquery-yui.html) 05:22:10 INFO - PROCESS | 1959 | RSS: Main: 306114560 05:22:10 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:57283/tests/dromaeo/cssquery-yui.html (next: http://localhost:57283/tests/dromaeo/cssquery-dojo.html) 05:25:12 INFO - PROCESS | 1959 | RSS: Main: 398336000 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | __start_tp_report 05:25:12 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 05:25:12 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 05:25:12 INFO - PROCESS | 1959 | |i|pagename|runs| 05:25:12 INFO - PROCESS | 1959 | |0;dojo.html;3196.696313850184;3488.5813702355704;3506.7545271830973;3511.806850623215;3538.203904121571;5795.884082318351;5800.071988481838;5804.59367844251;5811.709414529278;5826.4173582641715;5758.136279558068;5798.217240672508;5808.157817116516;5817.680027598481;5833.416658334165;5075.3909530856345;5147.897042059157;5150.999999999991;5180.870478238036;5287.4976877196605;5217.139172036609;5231.24146998685;5231.319928409302;5235.057689615867;5236.659617124894;5128.692278463285;5188.974055129719;5196;5219.425863155069;5233.084210263189;4652.278896771006;5227.1375223088335;5263.36839579253;5269.841904742864;5298.735063246861;5058.721770302632;5104.540591346759;5155.149400348975;5159.277701121846;5186.325777648919;5612.943870561283;5683.658980461186;5703.942960570383;5704.77180912763;5754.712264386806;6088.9391106088815;6176.351483094255;6188.164597779332;6201.100840378165;6212.378762123752;5851.4148585141575;6160.722767475474;6226.968865155691;6250.906236406412;6272.498200143978;5838.7080645967535;5877.353491115974;5877.4122587740885;5890.587658863838;5903.026000709878;6217.409346112112;6260.937390626035;6314.589551679218;6322.399372059647;6344.080108384258;6086.056661217518;6110.725017374229;6113.938860611426;6118.632882027004;6120.1737765401995;6195.070739389038;6204.317525072239;6206.100115483229;6206.472449841814;6222.62664240147;6095.116208149793;6118.20463339763;6119.927871922826;6171.47542458896;6196.0705894116245;6176.752929882754;6185.134081228632;6185.659788711664;6206.193194884636;6231.2522497300615;4918.213085906238;4921;4925.40895092584;4936.728479933567;4943.307936888838;4336.219480493542;4442.222611043119;4443.533428989909;4452.732836029849;4470.843520476833;5695.259616249861;5726.685032323179;5740.56945729072;5753.654780713171;5774.364819869811;6157.415045570664;6174.722137503823;6184.196054512884;6187.504999600022;6202.162708034448;5803.593748437569;5848.619839710405;5853;5856.677882716406;5888.116782482576;5371.973140134274;5393.27190829233;5395.73021348931;5397.10947693634;5415.268938693226;6238.157848690459;6350.714217860207;6357.427831495188;6368.172137622079;6385.584936979081;5239.842804715865;6238.656873871981;6239.469645080219;6252.624842509464;6255.655938923403;6339.397757213057;6355.078513615592;6422.875996700652;6426.775062872777;6429.03564465334;6093.9085913712215;6124.2588809726185;6124.958757011408;6154.169187159676;6212.875742485216;6203.875922481616;6228.5951413158;6231.439170474498;6233.906491402542;6234.875302494015;4638.93041604383;6113.816585502442;6203.906941395792;6217.09852071438;6222;6012.128241405059;6129.509639228941;6169.84575385619;6198.2252218472695;6215.12988181664;5775.566832487496;6047.213862197887;6082.817515474543;6084.69576521181;6087.421694938974;6002;6184.381561843779;6204.255489341308;6215.627062376272;6228.719707613259;5917.585768996301;6000.039993601177;6062.241949834499;6086.6348019119;6104.145419641165 05:25:12 INFO - PROCESS | 1959 | |1;ext.html;488.65549787399885;580.9912851307231;605.5821483176608;661.0447902780481;666.9399754022138;32030.878919237803;40895.59104408955;41194.17611647769;41227;41400.96497587562;43879.3418098728;44471.11057778842;44505.109897802104;44561.55438445615;44563.3315500267;9807.803843923117;10032.498375081253;10039.34744241622;10040.246981476383;10044.447555384451;9702.466364349957;9854.704358869245;9862.654807081753;9864.704058878246;9875.753106172368;9820.459874706889;10073.244506661993;10077.949610251939;10078.798424031515;10086.24353173515;9955.10404063634;10040.89959100407;10041.548130334117;10048.045435683598;10055.346402483816;9943.602255909755;9998.350107243006;10087.193024558092;10097.747556311151;10102.34334768245;42065.78967105145;42447;42525;42637.14725705468;42650;3189.1070500259966;3288.8355582220793;3325.9501107483406;3341.8329083545727;3733.1227161617;29558;29651.851740741375;29847.552286715716;29849.95525156609;30435.934742283913;2807.9859600701866;2811.592319113717;2841.545352743551;2853.18010699425;2935.222166125978;464167;464614;465426;465677.6716116398;467063;67851.66074169597;69332.3066769336;69368;69392.6530367345;69560.30439695538;78683;79873.80189297165;80056.59971700104;80176.59911700404;80288.19711802925;18336.63326733458;18472.538186545175;18526.629467410577;18544.907275463538;18560.071996400125;13954.372053257628;14025.859741402659;14026.298685065705;14030.088044277089;14032;22495.43761405945;22728.545429091326;22735.772642273365;22785;23220;9231.538423078953;9492.24393487207;9492.57939225507;9493.90506094944;9497.952510237405;967.3905439573165;984.8325784616633;1065.3181963543338;1091.7270682329417;1119.401120400598;30128.53266396491;30181.69818301833;30380.554530862264;30455.390892182477;30694.84652576723;1040.6409788622914;1112.404863398078;1141.3266172957829;1195.9402029898645;1212.927224366541;14495.637609059859;14587.343569539396;14870;14967.925160374129;14977.700445991019;655386;663187;664140;667808;668884;61980.69009655013;62522.68738656278;62567.374326257064;62646.37353626497;62704.68647656732;1414.5261337452068;1537.846615038721;1549.1866769945868;1596.4412455640202;1597.32912176886;28682.856585716938;30163.721348764317;32122.89805274911;33346.83326583449;33484.66515334913;59020;59410;59940;60019;60033.399666002784;507007;512397;512666;513052;513240;37736;45906.081878362915;46575.76712116418;46705.53294467012;47061.52938470572;43151.568484314754;43155.568444315155;44169.55830441655;44329.77835110804;44392.334114988946;35441.936741897785;39708;40400.59599404086;40684.79657601693;41478;30161.547576786805;36942.891713248646;37619.247615046996;37635.62364376321;39315.80342098386;38676.80661596674;38711;38749;38864;39332.80333598428;33675.66324336725;33763.662363377036;34382.65617343895;34393;34479.827600861834;21941.670874936568;25562.872185638953;25655.871720641277;25676;26257.737422626295 05:25:12 INFO - PROCESS | 1959 | |2;jquery.html;362.3622424532823;369.99630003699957;378.96399842015;381.17665841781746;387.4981898441518;19780.703289450652;20148.19407223711;20962.580748385022;21154.788452115474;21268.042938067785;20127.29554465594;20165.798342016576;20760.89619551904;20837.791622083776;21151.57696846062;5912.320083190445;6079.118527813464;6098.115773212881;6139.2325959255095;6143.754249830002;5875.970620146893;5917.023691090965;5995.85010374742;6029.879402411949;6082.60463069899;5998.190244317005;5998.340182579913;6008.279006519224;6011;6021.277446706401;5823.417658234185;5855.385184555625;5958.5828991970575;5974.3428222895445;5992.520598352121;5169.741512924376;5183.663061900965;5200.739963001873;5205.011047900886;5205.791768329263;25583.232503024938;25710.87144564284;26197.214083577524;26207.868960655076;26230.86884565584;1821.9089045547669;2081.843861710378;2176.183931025865;2201.2845825106906;2371.97628023721;9627.807443851083;9758.75603109928;9827.36122152065;9902.752431189207;10031.749206269902;1635.2232689472523;1676.8323167683257;1699.880430450381;1747.5718448979953;1762.480068379831;99130.50434747852;103839.48080259701;104708.47645761799;105440.47279763629;105461;9776.120149186465;11169.441527923735;11330.320180789178;11349.659510214708;11383.487743051583;13681.863181368259;14210;14292.285385730673;14316.57050288493;14318.713625727427;8631.058011947262;8785.780355491035;8803.031666516677;8886.24466920319;8886.4668119913;6735.8989615155815;8856.937167539807;8964.596593153325;8988.820223595492;9109.544522773967;4398.846040388571;4495.303227999665;4509.188346097734;4543.136804007228;4548.658850586219;1755.7893052833485;1851.7129844874066;1898.3260942365537;1913.7799153097571;1944.4069558784424;747.6187144556316;787.4999375396665;834.1950018232435;947.5830634520927;952.9571169297399;15834.524964251092;19011.61976760457;20155.468908695715;20184.899075504527;20267.986600670203;980.8577756225308;1021.8620486234412;1030.9896901031043;1036.6112707734599;1055.145332280855;10248.282620216512;10424.5830166794;10901.291416057931;10918.399488028235;10935.617253396093;605239;611886;620223;624482;627334;88577;111493;113742;114375.85624143819;115064.42467787607;1019.8266294729914;1042.9895701043044;1054.2620165883911;1064.6539874540806;1073.0932362153967;17541.1229438525;17724.91137544304;18085.36701215451;18218.72671909949;18380.540486487946;47336.526634733214;47461.7626911877;47728.28407573959;48379;48381.5161848377;71569.64215179099;73216.63391683008;74390;74536.62731686524;74575.62712186405;8078.272955434039;8173.877391839008;8197;8337.916620833957;8555.144485551395;9556.522173891417;12942.547010854574;13291.601251962456;13644.590662280147;13663.726725465634;11135.164862635496;11332.433378331216;11428.371439570903;11578.942105289756;12085.033197344368;11345.262557933707;11367.943160284145;11604.419779011185;11788.587399440978;11938.58214962472;11759;11796.52813887435;11887.762244754884;12035.217710848767;12118.394080295773;8390.160983901562;9981.702378690723;10835.965929539741;11364.602238921929;11785.351805650771;10864;11470.311781293149;11517.790631983522;11778.411079445823;12388.504459821506 05:25:12 INFO - PROCESS | 1959 | |3;mootools.html;1495.70833687431;1655.5861034741315;1683.5201967439275;2007.817609089593;2073.150008496517;8219.794505137375;8789.428687135332;8995.145461181193;9011;9013.324000699957;7524.96237518813;8683.305335573155;8932;8933.508657023862;8943.686970956018;2249.865008099511;2297.597920363938;2312.988435057831;2539.3016920346868;2568.640390345344;2528;2602.843829370244;2605.218434469661;2611.0730690604846;2617.986910065447;2482.6027835546315;2521.810864185184;2546.31249562618;2548.7578680025367;2550.8086893483064;2105.8525903186783;2510.698716154064;2528.519581279551;2538.936526586832;2545.350935511433;2507.0974449198275;2523.9116630918015;2536.7843733282693;2542.8092893033095;2564.1281964132286;9036.141566551232;9099.727008189699;9105.271578273721;9106.180443760028;9108.407953483002;5559.388467268597;5684.715764211773;5688.744006519716;5693.117566777156;5705.172749951275;3650.1969566695284;3679.337719210541;3745.9812700936595;3753.9812300938593;3771.830267637963;1043.4521876015153;1056.0548309263168;1071.8231491803842;1072.6567498400516;1078.687180717591;12309.507619695201;12358.75282494345;12360.44378002992;12371.381430928508;12381.380930953417;4328.956710432918;4886.60907127429;4915.361003069578;4957.107720610253;5059.645824792303;10423;10430.739231519121;10435.895641043644;10439.686809395731;10445.007724266183;5661.122526008476;5663.830085097454;5665.915011274834;5667.064934285879;5668.26312579362;4551.908961820745;4661.673682842168;4901.975490122598;5073.315102461194;5135.845924622267;3623.8912832615065;3822.961770382316;4072.816723247461;4096.365063415175;4166.270902592095;1333.2067419885152;1333.3599872061466;1338.8861946734442;1343.9529616463376;1347.730453909222;1420.4957240179795;1424.4515861393438;1424.886009119268;1448.4423496953539;1455.556055403091;8353.997520297604;8440.282575981111;8455.450395724258;8482.982042154981;8502.149785021573;517.3403910014762;521.4602886012958;526.6997811247629;527.4330095147654;527.4356438610649;6244.750209991549;6250.124982502453;6292.937070629235;6342.619442833445;6347.428731414103;10648.04167624902;10854.131669466424;10878.782424351468;10979.725506862393;11029.558817647205;10458.320209186379;10839.837402438812;10850.294730842628;10895.945520272506;10920.453977301104;8429.873551896726;8558.786030349167;8591.871121933176;8613.741587752378;8619.094995025558;8073.959630201812;8101.959490202511;8144.104148543774;8248.83502329962;8318.750437486886;9143.954280228556;9557.665481708107;9594.04059594035;9610.855837162308;9731.610735570497;9468.810623787347;9675.03249675055;9829.803403931739;9834.459104749307;9883.50582470888;6880.277570855188;7504.849903002018;7674.040744906887;7751.341136003277;7808.648610812641;8455.11221321777;8481.618327175169;8513.872291915503;8545.444546104733;8546.316294696533;7268.0914885639295;7418.591977441292;7499.550026998398;7501.737439189602;7523.623818808928;7379.557226566423;7408.777736667908;7413;7489.625518724151;7545.660445279866;7900.920990790249;7932.682692692228;7968.482048666818;7993.760187194393;8008.599570021592;7123.323284287984;7137.607431591312;7213.422926165999;7237.131544214728;7243.855122897618;7217.989481472703;7248.782536523912;7279.199288078201;7287.344139027513;7293.671784769592 05:25:12 INFO - PROCESS | 1959 | |4;prototype.html;327.59214777601886;329.5386458957459;333.1853617904225;334.7706820827732;335.6308061132754;6605.7357705691775;6649.8005059848165;6689.866202675944;6712.798616041527;6713.295104014075;6560.901586476207;6562.770303039395;6598.67006649668;6602.1747281589805;6617.569857959229;4625.190591646465;4782.952170478286;4786.090642777861;4910.459849416579;4911.72985485799;4846.8545943621575;4867.634927380443;4875.146849301375;4880.341153944208;4888.242322440027;4815.374001379816;4816.879578010543;4825.565699087081;4829.348038014876;4841.370621819158;3868.497095377611;4620.260758278676;4694.201985662428;4838.879028024292;4852.1508735971065;4117.07365842686;4120.938185927213;4124.752514849119;4139.586041395866;4143.08852052548;6286.49708023357;6369.904451433232;6379.36206379363;6385.489160867166;6394.840128996812;4642.442906851199;5722.170285308607;5733.397993210736;5736.82789516311;5756.6546007239285;6776.322367763233;6992.545484543489;7005.404540614054;7011.298870112999;7013.964930175316;4605.953940460619;4706.176419126673;4706.22347312693;4714.033623107255;4730.692504987165;7374.410047196211;7434.921936319258;7436.144843342977;7441.479096463249;7444.255574442566;4781.067691800135;4787.0904528139545;4790.592799612003;4793.209120495079;4811;4851.805927762921;4852.393450818648;4860.441049279308;4863.076015557032;4866.732329721828;4769.713817170981;5751.309842818889;5754.942450575524;5756.6685492375655;5767.423257674199;6771.221309549464;7051.89422158668;7084.822879427952;7086.858262834715;7093.787186384417;1661.6925868714327;1674.5646132005522;1680.6302613425028;1688.8902221355572;1690.1042447502844;1018.3075508654039;1061.8035663402295;1080.1844607321423;1091.3124731419314;1095.5727266366123;6387.169667943138;6451.806445806634;6586.506012049115;6597.274299827015;6698.96650516754;7342.816429589303;7345.338919497271;7358.521696089844;7358.889616655754;7363.48455608113;6638;6670.933290667128;6673.232578253466;6683.532152749358;6685.665716714242;3826.712996525216;3873.012381842668;3879.553851307079;3880.708946828999;3883.3786594145367;6968.44252459802;7340.96329518349;7405.852092925606;7661.808454788675;7692.423068269902;7534.849303013909;7592.202818704067;7595.202503737139;7616.619169041525;7617.619119044026;7643.617819109134;7647.923520764833;7662.272084151997;7666.54000759945;7677.385809135256;7136.571805691675;7267.709291628275;7372.631368431665;7373.59445230496;7404.888926665996;7158.463115266271;7206.56760594366;7233.891491627524;7244.818879528054;7246.166690830411;7620.885686714814;7647.311741943252;7649.005629268159;7661;7664.770056898302;4590.88522786933;4636.188666983332;4656.837010704609;4665.790039448165;4666.719996800203;7031;7282.817929551592;7335.669894854637;7344.596047217455;7347.926520734938;5929.407059294036;6071.72493776312;6653.866922661423;6903.654817259217;7047.330503602149;7073.328033836778;7074.504784665024;7083.185433675193;7088.610126443095;7114.822129446598;6908.723651053901;6917.757878474229;6924.792256232321;6925.722971081302;6933.168019837652;6707.798766037026;6973.75591854303;7054.470914681522;7059.082319298458;7082.858342833218;6600.471962243105;6759.966200168968;6765.560238584476;6900.654967251517;6923.446124310144 05:25:12 INFO - PROCESS | 1959 | |5;yui.html;692.7332976803931;698.744958090297;719.2950908110049;734.823642325842;789.0491957191584;15141.621459463518;15332.61668458289;15365.154916479603;15403.614909627266;15452.922735386335;14059.5079172229;14279;14288.642783930381;14291.642708932282;14300.642483937881;784.9568273744942;790.2729488870253;958.396210387455;986.2011770465909;986.6743974488437;962.302330810164;963.6868017894177;968.6464440479244;970.8252514547379;991.3457118301923;1016.9288149829515;1017.6285655735677;1019.7960407918409;1022.427440633244;1040.5577629507468;834.6494472321609;1026.7484466305766;1035.3270374256717;1049.171154787717;1050.889656586055;770.6955752477796;776.2741836382944;778.0119248554304;778.0585491555225;779.6413649721135;15324.770128448083;15387.4614388497;15415.845841541553;15441.0735355879;15485.922570387189;12807.359632018362;12862.549810756578;13090.149140305944;13093.41079651418;13141.342932853318;10109.39343639384;10249.897501024969;10258.589656413735;10433.373997560095;10679.733006674818;3547.0954906498678;3580.2481478889463;3580.7314451416246;3600.837962291703;3808.7143464240016;82232.17767822245;83470;83530;84234;84277.57861210716;9464;9796.314258001872;9800.705978820646;9847.852282215772;10651.094656954245;22632.434189145075;22658.66012009821;22661;22691.88654056752;22720.20479283217;6565.901511477332;6718.361755633207;6783.457323414115;6864.828379290558;6937.7224911004005;4092.6112019358116;4109;4112.794360281974;4139.5653456387245;4247.893802654959;3748.8687895923513;3787.7348585599293;3801.9239615208094;3805.6955443564448;3811.904702382463;617.3209469583422;629.6002038705456;631.4916492223712;640.1230314469113;640.38203133975;569.3822202909806;570.5378643298941;579.5276849367751;631.7346714379971;633.2259731997256;6560.737570497222;8166.183381661786;8232.17678232172;8336.041355244104;8363.540005299767;553.5156737854378;565.7256230728094;578.1991941161438;601.8405122642504;631.8009826904511;3775.1505911169766;3803.581606023335;3821.4458903458844;3880.9223815523533;3897.279003384383;91696;93682.53158734163;102690.48654756679;102700;107498;80574;81354.77967830487;82205.58897205476;83048;83337;65216.02175967364;65329;65409.67295163494;65667.99999999904;65802.34197657963;8265.421420500506;8347.415680902279;8401.579921003804;8446.118041890879;8640.740777776677;48278.75860620674;49309;50077;51447.48552514427;51663;66475;69756;70580.23549411306;70913;72498.91251631125;10727.517261723397;10970.177236707119;11047.116230701364;11283.323000619988;11806.704832379259;9707.9029209707;11689.824652630388;11728.41357932117;11948.880511194777;12353.3823308836;10979.39613321275;11167.983254005767;11494.310341379543;11585.768284634429;11650.76698466009;11178.608748693756;11450.312981221154;11459.968602825786;11554.59558915434;11608.6517404478;10609.045185933302;10928.071113955402;11182.888171118184;11271.323720576791;11277.7744445109;9192.95403522978;9488.478133702436;9517.428954262767;9659.547850951636;9803.068708472683;9099.863502047607;9150.40522366044;9528.761780955532;9553.570089346134;9610.903890961281 05:25:12 INFO - PROCESS | 1959 | __end_tp_report 05:25:12 INFO - PROCESS | 1959 | __start_cc_report 05:25:12 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,1157 05:25:12 INFO - PROCESS | 1959 | __end_cc_report 05:25:12 INFO - PROCESS | 1959 | __startTimestamp1460031912966__endTimestamp 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | ------- Summary: start ------- 05:25:12 INFO - PROCESS | 1959 | Number of tests: 6 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#0] dojo.html Cycles:165 Average:5747.84 Median:6012.13 stddev:641.44 (10.7%) stddev-sans-first:611.39 05:25:12 INFO - PROCESS | 1959 | Values: 3196.7 3488.6 3506.8 3511.8 3538.2 5795.9 5800.1 5804.6 5811.7 5826.4 5758.1 5798.2 5808.2 5817.7 5833.4 5075.4 5147.9 5151.0 5180.9 5287.5 5217.1 5231.2 5231.3 5235.1 5236.7 5128.7 5189.0 5196.0 5219.4 5233.1 4652.3 5227.1 5263.4 5269.8 5298.7 5058.7 5104.5 5155.1 5159.3 5186.3 5612.9 5683.7 5703.9 5704.8 5754.7 6088.9 6176.4 6188.2 6201.1 6212.4 5851.4 6160.7 6227.0 6250.9 6272.5 5838.7 5877.4 5877.4 5890.6 5903.0 6217.4 6260.9 6314.6 6322.4 6344.1 6086.1 6110.7 6113.9 6118.6 6120.2 6195.1 6204.3 6206.1 6206.5 6222.6 6095.1 6118.2 6119.9 6171.5 6196.1 6176.8 6185.1 6185.7 6206.2 6231.3 4918.2 4921.0 4925.4 4936.7 4943.3 4336.2 4442.2 4443.5 4452.7 4470.8 5695.3 5726.7 5740.6 5753.7 5774.4 6157.4 6174.7 6184.2 6187.5 6202.2 5803.6 5848.6 5853.0 5856.7 5888.1 5372.0 5393.3 5395.7 5397.1 5415.3 6238.2 6350.7 6357.4 6368.2 6385.6 5239.8 6238.7 6239.5 6252.6 6255.7 6339.4 6355.1 6422.9 6426.8 6429.0 6093.9 6124.3 6125.0 6154.2 6212.9 6203.9 6228.6 6231.4 6233.9 6234.9 4638.9 6113.8 6203.9 6217.1 6222.0 6012.1 6129.5 6169.8 6198.2 6215.1 5775.6 6047.2 6082.8 6084.7 6087.4 6002.0 6184.4 6204.3 6215.6 6228.7 5917.6 6000.0 6062.2 6086.6 6104.1 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#1] ext.html Cycles:180 Average:70349.15 Median:38693.90 stddev:147587.28 (381.4%) stddev-sans-first:147908.10 05:25:12 INFO - PROCESS | 1959 | Values: 488.7 581.0 605.6 661.0 666.9 32030.9 40895.6 41194.2 41227.0 41401.0 43879.3 44471.1 44505.1 44561.6 44563.3 9807.8 10032.5 10039.3 10040.2 10044.4 9702.5 9854.7 9862.7 9864.7 9875.8 9820.5 10073.2 10077.9 10078.8 10086.2 9955.1 10040.9 10041.5 10048.0 10055.3 9943.6 9998.4 10087.2 10097.7 10102.3 42065.8 42447.0 42525.0 42637.1 42650.0 3189.1 3288.8 3326.0 3341.8 3733.1 29558.0 29651.9 29847.6 29850.0 30435.9 2808.0 2811.6 2841.5 2853.2 2935.2 464167.0 464614.0 465426.0 465677.7 467063.0 67851.7 69332.3 69368.0 69392.7 69560.3 78683.0 79873.8 80056.6 80176.6 80288.2 18336.6 18472.5 18526.6 18544.9 18560.1 13954.4 14025.9 14026.3 14030.1 14032.0 22495.4 22728.5 22735.8 22785.0 23220.0 9231.5 9492.2 9492.6 9493.9 9498.0 967.4 984.8 1065.3 1091.7 1119.4 30128.5 30181.7 30380.6 30455.4 30694.8 1040.6 1112.4 1141.3 1195.9 1212.9 14495.6 14587.3 14870.0 14967.9 14977.7 655386.0 663187.0 664140.0 667808.0 668884.0 61980.7 62522.7 62567.4 62646.4 62704.7 1414.5 1537.8 1549.2 1596.4 1597.3 28682.9 30163.7 32122.9 33346.8 33484.7 59020.0 59410.0 59940.0 60019.0 60033.4 507007.0 512397.0 512666.0 513052.0 513240.0 37736.0 45906.1 46575.8 46705.5 47061.5 43151.6 43155.6 44169.6 44329.8 44392.3 35441.9 39708.0 40400.6 40684.8 41478.0 30161.5 36942.9 37619.2 37635.6 39315.8 38676.8 38711.0 38749.0 38864.0 39332.8 33675.7 33763.7 34382.7 34393.0 34479.8 21941.7 25562.9 25655.9 25676.0 26257.7 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#2] jquery.html Cycles:180 Average:34437.68 Median:11722.11 stddev:102137.79 (871.3%) stddev-sans-first:102392.27 05:25:12 INFO - PROCESS | 1959 | Values: 362.4 370.0 379.0 381.2 387.5 19780.7 20148.2 20962.6 21154.8 21268.0 20127.3 20165.8 20760.9 20837.8 21151.6 5912.3 6079.1 6098.1 6139.2 6143.8 5876.0 5917.0 5995.9 6029.9 6082.6 5998.2 5998.3 6008.3 6011.0 6021.3 5823.4 5855.4 5958.6 5974.3 5992.5 5169.7 5183.7 5200.7 5205.0 5205.8 25583.2 25710.9 26197.2 26207.9 26230.9 1821.9 2081.8 2176.2 2201.3 2372.0 9627.8 9758.8 9827.4 9902.8 10031.7 1635.2 1676.8 1699.9 1747.6 1762.5 99130.5 103839.5 104708.5 105440.5 105461.0 9776.1 11169.4 11330.3 11349.7 11383.5 13681.9 14210.0 14292.3 14316.6 14318.7 8631.1 8785.8 8803.0 8886.2 8886.5 6735.9 8856.9 8964.6 8988.8 9109.5 4398.8 4495.3 4509.2 4543.1 4548.7 1755.8 1851.7 1898.3 1913.8 1944.4 747.6 787.5 834.2 947.6 953.0 15834.5 19011.6 20155.5 20184.9 20268.0 980.9 1021.9 1031.0 1036.6 1055.1 10248.3 10424.6 10901.3 10918.4 10935.6 605239.0 611886.0 620223.0 624482.0 627334.0 88577.0 111493.0 113742.0 114375.9 115064.4 1019.8 1043.0 1054.3 1064.7 1073.1 17541.1 17724.9 18085.4 18218.7 18380.5 47336.5 47461.8 47728.3 48379.0 48381.5 71569.6 73216.6 74390.0 74536.6 74575.6 8078.3 8173.9 8197.0 8337.9 8555.1 9556.5 12942.5 13291.6 13644.6 13663.7 11135.2 11332.4 11428.4 11578.9 12085.0 11345.3 11367.9 11604.4 11788.6 11938.6 11759.0 11796.5 11887.8 12035.2 12118.4 8390.2 9981.7 10836.0 11364.6 11785.4 10864.0 11470.3 11517.8 11778.4 12388.5 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#3] mootools.html Cycles:180 Average:6177.69 Median:5610.26 stddev:3268.77 (58.3%) stddev-sans-first:3258.99 05:25:12 INFO - PROCESS | 1959 | Values: 1495.7 1655.6 1683.5 2007.8 2073.2 8219.8 8789.4 8995.1 9011.0 9013.3 7525.0 8683.3 8932.0 8933.5 8943.7 2249.9 2297.6 2313.0 2539.3 2568.6 2528.0 2602.8 2605.2 2611.1 2618.0 2482.6 2521.8 2546.3 2548.8 2550.8 2105.9 2510.7 2528.5 2538.9 2545.4 2507.1 2523.9 2536.8 2542.8 2564.1 9036.1 9099.7 9105.3 9106.2 9108.4 5559.4 5684.7 5688.7 5693.1 5705.2 3650.2 3679.3 3746.0 3754.0 3771.8 1043.5 1056.1 1071.8 1072.7 1078.7 12309.5 12358.8 12360.4 12371.4 12381.4 4329.0 4886.6 4915.4 4957.1 5059.6 10423.0 10430.7 10435.9 10439.7 10445.0 5661.1 5663.8 5665.9 5667.1 5668.3 4551.9 4661.7 4902.0 5073.3 5135.8 3623.9 3823.0 4072.8 4096.4 4166.3 1333.2 1333.4 1338.9 1344.0 1347.7 1420.5 1424.5 1424.9 1448.4 1455.6 8354.0 8440.3 8455.5 8483.0 8502.1 517.3 521.5 526.7 527.4 527.4 6244.8 6250.1 6292.9 6342.6 6347.4 10648.0 10854.1 10878.8 10979.7 11029.6 10458.3 10839.8 10850.3 10895.9 10920.5 8429.9 8558.8 8591.9 8613.7 8619.1 8074.0 8102.0 8144.1 8248.8 8318.8 9144.0 9557.7 9594.0 9610.9 9731.6 9468.8 9675.0 9829.8 9834.5 9883.5 6880.3 7504.8 7674.0 7751.3 7808.6 8455.1 8481.6 8513.9 8545.4 8546.3 7268.1 7418.6 7499.6 7501.7 7523.6 7379.6 7408.8 7413.0 7489.6 7545.7 7900.9 7932.7 7968.5 7993.8 8008.6 7123.3 7137.6 7213.4 7237.1 7243.9 7218.0 7248.8 7279.2 7287.3 7293.7 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#4] prototype.html Cycles:180 Average:5784.01 Median:6603.96 stddev:1841.33 (27.9%) stddev-sans-first:1800.37 05:25:12 INFO - PROCESS | 1959 | Values: 327.6 329.5 333.2 334.8 335.6 6605.7 6649.8 6689.9 6712.8 6713.3 6560.9 6562.8 6598.7 6602.2 6617.6 4625.2 4783.0 4786.1 4910.5 4911.7 4846.9 4867.6 4875.1 4880.3 4888.2 4815.4 4816.9 4825.6 4829.3 4841.4 3868.5 4620.3 4694.2 4838.9 4852.2 4117.1 4120.9 4124.8 4139.6 4143.1 6286.5 6369.9 6379.4 6385.5 6394.8 4642.4 5722.2 5733.4 5736.8 5756.7 6776.3 6992.5 7005.4 7011.3 7014.0 4606.0 4706.2 4706.2 4714.0 4730.7 7374.4 7434.9 7436.1 7441.5 7444.3 4781.1 4787.1 4790.6 4793.2 4811.0 4851.8 4852.4 4860.4 4863.1 4866.7 4769.7 5751.3 5754.9 5756.7 5767.4 6771.2 7051.9 7084.8 7086.9 7093.8 1661.7 1674.6 1680.6 1688.9 1690.1 1018.3 1061.8 1080.2 1091.3 1095.6 6387.2 6451.8 6586.5 6597.3 6699.0 7342.8 7345.3 7358.5 7358.9 7363.5 6638.0 6670.9 6673.2 6683.5 6685.7 3826.7 3873.0 3879.6 3880.7 3883.4 6968.4 7341.0 7405.9 7661.8 7692.4 7534.8 7592.2 7595.2 7616.6 7617.6 7643.6 7647.9 7662.3 7666.5 7677.4 7136.6 7267.7 7372.6 7373.6 7404.9 7158.5 7206.6 7233.9 7244.8 7246.2 7620.9 7647.3 7649.0 7661.0 7664.8 4590.9 4636.2 4656.8 4665.8 4666.7 7031.0 7282.8 7335.7 7344.6 7347.9 5929.4 6071.7 6653.9 6903.7 7047.3 7073.3 7074.5 7083.2 7088.6 7114.8 6908.7 6917.8 6924.8 6925.7 6933.2 6707.8 6973.8 7054.5 7059.1 7082.9 6600.5 6760.0 6765.6 6900.7 6923.4 05:25:12 INFO - PROCESS | 1959 | 05:25:12 INFO - PROCESS | 1959 | [#5] yui.html Cycles:180 Average:18697.19 Median:51555.24 stddev:26662.46 (51.7%) stddev-sans-first:26702.98 05:25:12 INFO - PROCESS | 1959 | Values: 692.7 698.7 719.3 734.8 789.0 15141.6 15332.6 15365.2 15403.6 15452.9 14059.5 14279.0 14288.6 14291.6 14300.6 785.0 790.3 958.4 986.2 986.7 962.3 963.7 968.6 970.8 991.3 1016.9 1017.6 1019.8 1022.4 1040.6 834.6 1026.7 1035.3 1049.2 1050.9 770.7 776.3 778.0 778.1 779.6 15324.8 15387.5 15415.8 15441.1 15485.9 12807.4 12862.5 13090.1 13093.4 13141.3 10109.4 10249.9 10258.6 10433.4 10679.7 3547.1 3580.2 3580.7 3600.8 3808.7 82232.2 83470.0 83530.0 84234.0 84277.6 9464.0 9796.3 9800.7 9847.9 10651.1 22632.4 22658.7 22661.0 22691.9 22720.2 6565.9 6718.4 6783.5 6864.8 6937.7 4092.6 4109.0 4112.8 4139.6 4247.9 3748.9 3787.7 3801.9 3805.7 3811.9 617.3 629.6 631.5 640.1 640.4 569.4 570.5 579.5 631.7 633.2 6560.7 8166.2 8232.2 8336.0 8363.5 553.5 565.7 578.2 601.8 631.8 3775.2 3803.6 3821.4 3880.9 3897.3 91696.0 93682.5 102690.5 102700.0 107498.0 80574.0 81354.8 82205.6 83048.0 83337.0 65216.0 65329.0 65409.7 65668.0 65802.3 8265.4 8347.4 8401.6 8446.1 8640.7 48278.8 49309.0 50077.0 51447.5 51663.0 66475.0 69756.0 70580.2 70913.0 72498.9 10727.5 10970.2 11047.1 11283.3 11806.7 9707.9 11689.8 11728.4 11948.9 12353.4 10979.4 11168.0 11494.3 11585.8 11650.8 11178.6 11450.3 11460.0 11554.6 11608.7 10609.0 10928.1 11182.9 11271.3 11277.8 9193.0 9488.5 9517.4 9659.5 9803.1 9099.9 9150.4 9528.8 9553.6 9610.9 05:25:12 INFO - PROCESS | 1959 | -------- Summary: end -------- 05:25:12 INFO - PROCESS | 1959 | 05:25:13 INFO - TEST-INFO | 1959: exit 0 05:25:13 INFO - TEST-OK | dromaeo_css | took 1112210ms 05:25:13 INFO - TEST-START | kraken 05:25:13 INFO - operating with platform_type : mac_ 05:25:13 INFO - Initialising browser for kraken test... 05:25:13 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpGbyf4m/profile http://localhost:57283/getInfo.html) 05:25:15 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 05:25:15 INFO - PROCESS | 1962 | colorDepth:24 05:25:15 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 05:25:15 INFO - PROCESS | 1962 | __metrics 05:25:15 INFO - PROCESS | 1962 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:25:15 INFO - PROCESS | 1962 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:16 INFO - TEST-INFO | 1962: exit 0 05:25:16 INFO - Browser initialized. 05:25:16 INFO - Running cycle 1/1 for kraken test... 05:25:16 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpGbyf4m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:28 INFO - PROCESS | 1964 | RSS: Main: 207900672 05:25:28 INFO - PROCESS | 1964 | 05:25:30 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57283/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:31 INFO - PROCESS | 1964 | RSS: Main: 263319552 05:25:31 INFO - PROCESS | 1964 | 05:25:34 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57283/tests/kraken/driver.html?testName=audio-dft) 05:25:34 INFO - PROCESS | 1964 | RSS: Main: 272932864 05:25:34 INFO - PROCESS | 1964 | 05:25:37 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57283/tests/kraken/driver.html?testName=audio-fft) 05:25:38 INFO - PROCESS | 1964 | RSS: Main: 263725056 05:25:38 INFO - PROCESS | 1964 | 05:25:40 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57283/tests/kraken/driver.html?testName=audio-oscillator) 05:25:41 INFO - PROCESS | 1964 | RSS: Main: 265928704 05:25:41 INFO - PROCESS | 1964 | 05:25:43 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:43 INFO - PROCESS | 1964 | RSS: Main: 266047488 05:25:43 INFO - PROCESS | 1964 | 05:25:46 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57283/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:46 INFO - PROCESS | 1964 | RSS: Main: 265908224 05:25:46 INFO - PROCESS | 1964 | 05:25:49 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57283/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:50 INFO - PROCESS | 1964 | RSS: Main: 266100736 05:25:50 INFO - PROCESS | 1964 | 05:25:53 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57283/tests/kraken/driver.html?testName=json-parse-financial) 05:25:53 INFO - PROCESS | 1964 | RSS: Main: 266379264 05:25:53 INFO - PROCESS | 1964 | 05:25:55 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:55 INFO - PROCESS | 1964 | RSS: Main: 299933696 05:25:55 INFO - PROCESS | 1964 | 05:25:57 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:58 INFO - PROCESS | 1964 | RSS: Main: 300765184 05:25:58 INFO - PROCESS | 1964 | 05:26:00 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:01 INFO - PROCESS | 1964 | RSS: Main: 307056640 05:26:01 INFO - PROCESS | 1964 | 05:26:03 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:04 INFO - PROCESS | 1964 | RSS: Main: 307609600 05:26:04 INFO - PROCESS | 1964 | 05:26:06 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:07 INFO - PROCESS | 1964 | RSS: Main: 307814400 05:26:07 INFO - PROCESS | 1964 | 05:26:08 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:57283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57283/tests/kraken/driver.html?testName=ai-astar) 05:26:09 INFO - PROCESS | 1964 | RSS: Main: 369995776 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | __start_tp_report 05:26:09 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 05:26:09 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 05:26:09 INFO - PROCESS | 1964 | |i|pagename|runs| 05:26:09 INFO - PROCESS | 1964 | |0;ai-astar;112;110;112;111;113;112;120;114;109;111 05:26:09 INFO - PROCESS | 1964 | |1;audio-beat-detection;159;140;179;169;137;175;158;111;190;145 05:26:09 INFO - PROCESS | 1964 | |2;audio-dft;189;185;183;186;184;186;185;187;197;256 05:26:09 INFO - PROCESS | 1964 | |3;audio-fft;79;71;73;117;56;59;56;58;123;55 05:26:09 INFO - PROCESS | 1964 | |4;audio-oscillator;92;95;129;82;78;85;120;77;77;118 05:26:09 INFO - PROCESS | 1964 | |5;imaging-gaussian-blur;89;85;88;86;88;85;89;84;88;84 05:26:09 INFO - PROCESS | 1964 | |6;imaging-darkroom;92;93;94;93;107;147;94;94;93;93 05:26:09 INFO - PROCESS | 1964 | |7;imaging-desaturate;83;86;84;84;84;86;84;84;83;86 05:26:09 INFO - PROCESS | 1964 | |8;json-parse-financial;54;59;56;58;58;57;58;68;51;55 05:26:09 INFO - PROCESS | 1964 | |9;json-stringify-tinderbox;64;69;61;73;71;70;66;65;66;67 05:26:09 INFO - PROCESS | 1964 | |10;stanford-crypto-aes;80;81;82;82;101;78;69;95;95;108 05:26:09 INFO - PROCESS | 1964 | |11;stanford-crypto-ccm;130;145;135;129;117;115;116;117;118;121 05:26:09 INFO - PROCESS | 1964 | |12;stanford-crypto-pbkdf2;134;123;134;134;131;124;136;131;130;133 05:26:09 INFO - PROCESS | 1964 | |13;stanford-crypto-sha256-iterative;65;66;65;65;67;64;65;65;65;64 05:26:09 INFO - PROCESS | 1964 | __end_tp_report 05:26:09 INFO - PROCESS | 1964 | __start_cc_report 05:26:09 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,1227 05:26:09 INFO - PROCESS | 1964 | __end_cc_report 05:26:09 INFO - PROCESS | 1964 | __startTimestamp1460031969100__endTimestamp 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | ------- Summary: start ------- 05:26:09 INFO - PROCESS | 1964 | Number of tests: 14 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.00 stddev:3.03 (2.7%) stddev-sans-first:3.21 05:26:09 INFO - PROCESS | 1964 | Values: 112.0 110.0 112.0 111.0 113.0 112.0 120.0 114.0 109.0 111.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#1] audio-beat-detection Cycles:10 Average:156.30 Median:164.00 stddev:23.55 (14.4%) stddev-sans-first:24.95 05:26:09 INFO - PROCESS | 1964 | Values: 159.0 140.0 179.0 169.0 137.0 175.0 158.0 111.0 190.0 145.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#2] audio-dft Cycles:10 Average:193.80 Median:186.50 stddev:22.21 (11.9%) stddev-sans-first:23.48 05:26:09 INFO - PROCESS | 1964 | Values: 189.0 185.0 183.0 186.0 184.0 186.0 185.0 187.0 197.0 256.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#3] audio-fft Cycles:10 Average:74.70 Median:58.50 stddev:25.32 (43.3%) stddev-sans-first:26.81 05:26:09 INFO - PROCESS | 1964 | Values: 79.0 71.0 73.0 117.0 56.0 59.0 56.0 58.0 123.0 55.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:80.00 stddev:19.79 (24.7%) stddev-sans-first:20.95 05:26:09 INFO - PROCESS | 1964 | Values: 92.0 95.0 129.0 82.0 78.0 85.0 120.0 77.0 77.0 118.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:2.01 (2.3%) stddev-sans-first:1.94 05:26:09 INFO - PROCESS | 1964 | Values: 89.0 85.0 88.0 86.0 88.0 85.0 89.0 84.0 88.0 84.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:93.00 stddev:17.08 (18.4%) stddev-sans-first:17.87 05:26:09 INFO - PROCESS | 1964 | Values: 92.0 93.0 94.0 93.0 107.0 147.0 94.0 94.0 93.0 93.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.17 (1.4%) stddev-sans-first:1.13 05:26:09 INFO - PROCESS | 1964 | Values: 83.0 86.0 84.0 84.0 84.0 86.0 84.0 84.0 83.0 86.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:4.43 (7.6%) stddev-sans-first:4.52 05:26:09 INFO - PROCESS | 1964 | Values: 54.0 59.0 56.0 58.0 58.0 57.0 58.0 68.0 51.0 55.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:68.00 stddev:3.58 (5.3%) stddev-sans-first:3.61 05:26:09 INFO - PROCESS | 1964 | Values: 64.0 69.0 61.0 73.0 71.0 70.0 66.0 65.0 66.0 67.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:81.50 stddev:12.04 (14.8%) stddev-sans-first:12.49 05:26:09 INFO - PROCESS | 1964 | Values: 80.0 81.0 82.0 82.0 101.0 78.0 69.0 95.0 95.0 108.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:125.00 stddev:10.06 (8.0%) stddev-sans-first:10.45 05:26:09 INFO - PROCESS | 1964 | Values: 130.0 145.0 135.0 129.0 117.0 115.0 116.0 117.0 118.0 121.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.00 Median:133.50 stddev:4.35 (3.3%) stddev-sans-first:4.47 05:26:09 INFO - PROCESS | 1964 | Values: 134.0 123.0 134.0 134.0 131.0 124.0 136.0 131.0 130.0 133.0 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - PROCESS | 1964 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 05:26:09 INFO - PROCESS | 1964 | Values: 65.0 66.0 65.0 65.0 67.0 64.0 65.0 65.0 65.0 64.0 05:26:09 INFO - PROCESS | 1964 | -------- Summary: end -------- 05:26:09 INFO - PROCESS | 1964 | 05:26:09 INFO - TEST-INFO | 1964: exit 0 05:26:09 INFO - TEST-OK | kraken | took 56476ms 05:26:09 INFO - SUITE-END | took 1168s 05:26:10 INFO - Completed test suite (00:19:28) 05:26:10 INFO - javascript benchmark 05:26:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3196.696313850184, 3488.5813702355704, 3506.7545271830973, 3511.806850623215, 3538.203904121571, 5795.884082318351, 5800.071988481838, 5804.59367844251, 5811.709414529278, 5826.4173582641715, 5758.136279558068, 5798.217240672508, 5808.157817116516, 5817.680027598481, 5833.416658334165, 5075.3909530856345, 5147.897042059157, 5150.999999999991, 5180.870478238036, 5287.4976877196605, 5217.139172036609, 5231.24146998685, 5231.319928409302, 5235.057689615867, 5236.659617124894, 5128.692278463285, 5188.974055129719, 5196.0, 5219.425863155069, 5233.084210263189, 4652.278896771006, 5227.1375223088335, 5263.36839579253, 5269.841904742864, 5298.735063246861, 5058.721770302632, 5104.540591346759, 5155.149400348975, 5159.277701121846, 5186.325777648919, 5612.943870561283, 5683.658980461186, 5703.942960570383, 5704.77180912763, 5754.712264386806, 6088.9391106088815, 6176.351483094255, 6188.164597779332, 6201.100840378165, 6212.378762123752, 5851.4148585141575, 6160.722767475474, 6226.968865155691, 6250.906236406412, 6272.498200143978, 5838.7080645967535, 5877.353491115974, 5877.4122587740885, 5890.587658863838, 5903.026000709878, 6217.409346112112, 6260.937390626035, 6314.589551679218, 6322.399372059647, 6344.080108384258, 6086.056661217518, 6110.725017374229, 6113.938860611426, 6118.632882027004, 6120.1737765401995, 6195.070739389038, 6204.317525072239, 6206.100115483229, 6206.472449841814, 6222.62664240147, 6095.116208149793, 6118.20463339763, 6119.927871922826, 6171.47542458896, 6196.0705894116245, 6176.752929882754, 6185.134081228632, 6185.659788711664, 6206.193194884636, 6231.2522497300615, 4918.213085906238, 4921.0, 4925.40895092584, 4936.728479933567, 4943.307936888838, 4336.219480493542, 4442.222611043119, 4443.533428989909, 4452.732836029849, 4470.843520476833, 5695.259616249861, 5726.685032323179, 5740.56945729072, 5753.654780713171, 5774.364819869811, 6157.415045570664, 6174.722137503823, 6184.196054512884, 6187.504999600022, 6202.162708034448, 5803.593748437569, 5848.619839710405, 5853.0, 5856.677882716406, 5888.116782482576, 5371.973140134274, 5393.27190829233, 5395.73021348931, 5397.10947693634, 5415.268938693226, 6238.157848690459, 6350.714217860207, 6357.427831495188, 6368.172137622079, 6385.584936979081, 5239.842804715865, 6238.656873871981, 6239.469645080219, 6252.624842509464, 6255.655938923403, 6339.397757213057, 6355.078513615592, 6422.875996700652, 6426.775062872777, 6429.03564465334, 6093.9085913712215, 6124.2588809726185, 6124.958757011408, 6154.169187159676, 6212.875742485216, 6203.875922481616, 6228.5951413158, 6231.439170474498, 6233.906491402542, 6234.875302494015, 4638.93041604383, 6113.816585502442, 6203.906941395792, 6217.09852071438, 6222.0, 6012.128241405059, 6129.509639228941, 6169.84575385619, 6198.2252218472695, 6215.12988181664, 5775.566832487496, 6047.213862197887, 6082.817515474543, 6084.69576521181, 6087.421694938974, 6002.0, 6184.381561843779, 6204.255489341308, 6215.627062376272, 6228.719707613259, 5917.585768996301, 6000.039993601177, 6062.241949834499, 6086.6348019119, 6104.145419641165], "unit": "score", "name": "dojo.html", "value": 5707.985969287017}, {"lowerIsBetter": false, "replicates": [488.65549787399885, 580.9912851307231, 605.5821483176608, 661.0447902780481, 666.9399754022138, 32030.878919237803, 40895.59104408955, 41194.17611647769, 41227.0, 41400.96497587562, 43879.3418098728, 44471.11057778842, 44505.109897802104, 44561.55438445615, 44563.3315500267, 9807.803843923117, 10032.498375081253, 10039.34744241622, 10040.246981476383, 10044.447555384451, 9702.466364349957, 9854.704358869245, 9862.654807081753, 9864.704058878246, 9875.753106172368, 9820.459874706889, 10073.244506661993, 10077.949610251939, 10078.798424031515, 10086.24353173515, 9955.10404063634, 10040.89959100407, 10041.548130334117, 10048.045435683598, 10055.346402483816, 9943.602255909755, 9998.350107243006, 10087.193024558092, 10097.747556311151, 10102.34334768245, 42065.78967105145, 42447.0, 42525.0, 42637.14725705468, 42650.0, 3189.1070500259966, 3288.8355582220793, 3325.9501107483406, 3341.8329083545727, 3733.1227161617, 29558.0, 29651.851740741375, 29847.552286715716, 29849.95525156609, 30435.934742283913, 2807.9859600701866, 2811.592319113717, 2841.545352743551, 2853.18010699425, 2935.222166125978, 464167.0, 464614.0, 465426.0, 465677.6716116398, 467063.0, 67851.66074169597, 69332.3066769336, 69368.0, 69392.6530367345, 69560.30439695538, 78683.0, 79873.80189297165, 80056.59971700104, 80176.59911700404, 80288.19711802925, 18336.63326733458, 18472.538186545175, 18526.629467410577, 18544.907275463538, 18560.071996400125, 13954.372053257628, 14025.859741402659, 14026.298685065705, 14030.088044277089, 14032.0, 22495.43761405945, 22728.545429091326, 22735.772642273365, 22785.0, 23220.0, 9231.538423078953, 9492.24393487207, 9492.57939225507, 9493.90506094944, 9497.952510237405, 967.3905439573165, 984.8325784616633, 1065.3181963543338, 1091.7270682329417, 1119.401120400598, 30128.53266396491, 30181.69818301833, 30380.554530862264, 30455.390892182477, 30694.84652576723, 1040.6409788622914, 1112.404863398078, 1141.3266172957829, 1195.9402029898645, 1212.927224366541, 14495.637609059859, 14587.343569539396, 14870.0, 14967.925160374129, 14977.700445991019, 655386.0, 663187.0, 664140.0, 667808.0, 668884.0, 61980.69009655013, 62522.68738656278, 62567.374326257064, 62646.37353626497, 62704.68647656732, 1414.5261337452068, 1537.846615038721, 1549.1866769945868, 1596.4412455640202, 1597.32912176886, 28682.856585716938, 30163.721348764317, 32122.89805274911, 33346.83326583449, 33484.66515334913, 59020.0, 59410.0, 59940.0, 60019.0, 60033.399666002784, 507007.0, 512397.0, 512666.0, 513052.0, 513240.0, 37736.0, 45906.081878362915, 46575.76712116418, 46705.53294467012, 47061.52938470572, 43151.568484314754, 43155.568444315155, 44169.55830441655, 44329.77835110804, 44392.334114988946, 35441.936741897785, 39708.0, 40400.59599404086, 40684.79657601693, 41478.0, 30161.547576786805, 36942.891713248646, 37619.247615046996, 37635.62364376321, 39315.80342098386, 38676.80661596674, 38711.0, 38749.0, 38864.0, 39332.80333598428, 33675.66324336725, 33763.662363377036, 34382.65617343895, 34393.0, 34479.827600861834, 21941.670874936568, 25562.872185638953, 25655.871720641277, 25676.0, 26257.737422626295], "unit": "score", "name": "ext.html", "value": 21203.993419929706}, {"lowerIsBetter": false, "replicates": [362.3622424532823, 369.99630003699957, 378.96399842015, 381.17665841781746, 387.4981898441518, 19780.703289450652, 20148.19407223711, 20962.580748385022, 21154.788452115474, 21268.042938067785, 20127.29554465594, 20165.798342016576, 20760.89619551904, 20837.791622083776, 21151.57696846062, 5912.320083190445, 6079.118527813464, 6098.115773212881, 6139.2325959255095, 6143.754249830002, 5875.970620146893, 5917.023691090965, 5995.85010374742, 6029.879402411949, 6082.60463069899, 5998.190244317005, 5998.340182579913, 6008.279006519224, 6011.0, 6021.277446706401, 5823.417658234185, 5855.385184555625, 5958.5828991970575, 5974.3428222895445, 5992.520598352121, 5169.741512924376, 5183.663061900965, 5200.739963001873, 5205.011047900886, 5205.791768329263, 25583.232503024938, 25710.87144564284, 26197.214083577524, 26207.868960655076, 26230.86884565584, 1821.9089045547669, 2081.843861710378, 2176.183931025865, 2201.2845825106906, 2371.97628023721, 9627.807443851083, 9758.75603109928, 9827.36122152065, 9902.752431189207, 10031.749206269902, 1635.2232689472523, 1676.8323167683257, 1699.880430450381, 1747.5718448979953, 1762.480068379831, 99130.50434747852, 103839.48080259701, 104708.47645761799, 105440.47279763629, 105461.0, 9776.120149186465, 11169.441527923735, 11330.320180789178, 11349.659510214708, 11383.487743051583, 13681.863181368259, 14210.0, 14292.285385730673, 14316.57050288493, 14318.713625727427, 8631.058011947262, 8785.780355491035, 8803.031666516677, 8886.24466920319, 8886.4668119913, 6735.8989615155815, 8856.937167539807, 8964.596593153325, 8988.820223595492, 9109.544522773967, 4398.846040388571, 4495.303227999665, 4509.188346097734, 4543.136804007228, 4548.658850586219, 1755.7893052833485, 1851.7129844874066, 1898.3260942365537, 1913.7799153097571, 1944.4069558784424, 747.6187144556316, 787.4999375396665, 834.1950018232435, 947.5830634520927, 952.9571169297399, 15834.524964251092, 19011.61976760457, 20155.468908695715, 20184.899075504527, 20267.986600670203, 980.8577756225308, 1021.8620486234412, 1030.9896901031043, 1036.6112707734599, 1055.145332280855, 10248.282620216512, 10424.5830166794, 10901.291416057931, 10918.399488028235, 10935.617253396093, 605239.0, 611886.0, 620223.0, 624482.0, 627334.0, 88577.0, 111493.0, 113742.0, 114375.85624143819, 115064.42467787607, 1019.8266294729914, 1042.9895701043044, 1054.2620165883911, 1064.6539874540806, 1073.0932362153967, 17541.1229438525, 17724.91137544304, 18085.36701215451, 18218.72671909949, 18380.540486487946, 47336.526634733214, 47461.7626911877, 47728.28407573959, 48379.0, 48381.5161848377, 71569.64215179099, 73216.63391683008, 74390.0, 74536.62731686524, 74575.62712186405, 8078.272955434039, 8173.877391839008, 8197.0, 8337.916620833957, 8555.144485551395, 9556.522173891417, 12942.547010854574, 13291.601251962456, 13644.590662280147, 13663.726725465634, 11135.164862635496, 11332.433378331216, 11428.371439570903, 11578.942105289756, 12085.033197344368, 11345.262557933707, 11367.943160284145, 11604.419779011185, 11788.587399440978, 11938.58214962472, 11759.0, 11796.52813887435, 11887.762244754884, 12035.217710848767, 12118.394080295773, 8390.160983901562, 9981.702378690723, 10835.965929539741, 11364.602238921929, 11785.351805650771, 10864.0, 11470.311781293149, 11517.790631983522, 11778.411079445823, 12388.504459821506], "unit": "score", "name": "jquery.html", "value": 9512.444535323666}, {"lowerIsBetter": false, "replicates": [1495.70833687431, 1655.5861034741315, 1683.5201967439275, 2007.817609089593, 2073.150008496517, 8219.794505137375, 8789.428687135332, 8995.145461181193, 9011.0, 9013.324000699957, 7524.96237518813, 8683.305335573155, 8932.0, 8933.508657023862, 8943.686970956018, 2249.865008099511, 2297.597920363938, 2312.988435057831, 2539.3016920346868, 2568.640390345344, 2528.0, 2602.843829370244, 2605.218434469661, 2611.0730690604846, 2617.986910065447, 2482.6027835546315, 2521.810864185184, 2546.31249562618, 2548.7578680025367, 2550.8086893483064, 2105.8525903186783, 2510.698716154064, 2528.519581279551, 2538.936526586832, 2545.350935511433, 2507.0974449198275, 2523.9116630918015, 2536.7843733282693, 2542.8092893033095, 2564.1281964132286, 9036.141566551232, 9099.727008189699, 9105.271578273721, 9106.180443760028, 9108.407953483002, 5559.388467268597, 5684.715764211773, 5688.744006519716, 5693.117566777156, 5705.172749951275, 3650.1969566695284, 3679.337719210541, 3745.9812700936595, 3753.9812300938593, 3771.830267637963, 1043.4521876015153, 1056.0548309263168, 1071.8231491803842, 1072.6567498400516, 1078.687180717591, 12309.507619695201, 12358.75282494345, 12360.44378002992, 12371.381430928508, 12381.380930953417, 4328.956710432918, 4886.60907127429, 4915.361003069578, 4957.107720610253, 5059.645824792303, 10423.0, 10430.739231519121, 10435.895641043644, 10439.686809395731, 10445.007724266183, 5661.122526008476, 5663.830085097454, 5665.915011274834, 5667.064934285879, 5668.26312579362, 4551.908961820745, 4661.673682842168, 4901.975490122598, 5073.315102461194, 5135.845924622267, 3623.8912832615065, 3822.961770382316, 4072.816723247461, 4096.365063415175, 4166.270902592095, 1333.2067419885152, 1333.3599872061466, 1338.8861946734442, 1343.9529616463376, 1347.730453909222, 1420.4957240179795, 1424.4515861393438, 1424.886009119268, 1448.4423496953539, 1455.556055403091, 8353.997520297604, 8440.282575981111, 8455.450395724258, 8482.982042154981, 8502.149785021573, 517.3403910014762, 521.4602886012958, 526.6997811247629, 527.4330095147654, 527.4356438610649, 6244.750209991549, 6250.124982502453, 6292.937070629235, 6342.619442833445, 6347.428731414103, 10648.04167624902, 10854.131669466424, 10878.782424351468, 10979.725506862393, 11029.558817647205, 10458.320209186379, 10839.837402438812, 10850.294730842628, 10895.945520272506, 10920.453977301104, 8429.873551896726, 8558.786030349167, 8591.871121933176, 8613.741587752378, 8619.094995025558, 8073.959630201812, 8101.959490202511, 8144.104148543774, 8248.83502329962, 8318.750437486886, 9143.954280228556, 9557.665481708107, 9594.04059594035, 9610.855837162308, 9731.610735570497, 9468.810623787347, 9675.03249675055, 9829.803403931739, 9834.459104749307, 9883.50582470888, 6880.277570855188, 7504.849903002018, 7674.040744906887, 7751.341136003277, 7808.648610812641, 8455.11221321777, 8481.618327175169, 8513.872291915503, 8545.444546104733, 8546.316294696533, 7268.0914885639295, 7418.591977441292, 7499.550026998398, 7501.737439189602, 7523.623818808928, 7379.557226566423, 7408.777736667908, 7413.0, 7489.625518724151, 7545.660445279866, 7900.920990790249, 7932.682692692228, 7968.482048666818, 7993.760187194393, 8008.599570021592, 7123.323284287984, 7137.607431591312, 7213.422926165999, 7237.131544214728, 7243.855122897618, 7217.989481472703, 7248.782536523912, 7279.199288078201, 7287.344139027513, 7293.671784769592], "unit": "score", "name": "mootools.html", "value": 4961.542620099386}, {"lowerIsBetter": false, "replicates": [327.59214777601886, 329.5386458957459, 333.1853617904225, 334.7706820827732, 335.6308061132754, 6605.7357705691775, 6649.8005059848165, 6689.866202675944, 6712.798616041527, 6713.295104014075, 6560.901586476207, 6562.770303039395, 6598.67006649668, 6602.1747281589805, 6617.569857959229, 4625.190591646465, 4782.952170478286, 4786.090642777861, 4910.459849416579, 4911.72985485799, 4846.8545943621575, 4867.634927380443, 4875.146849301375, 4880.341153944208, 4888.242322440027, 4815.374001379816, 4816.879578010543, 4825.565699087081, 4829.348038014876, 4841.370621819158, 3868.497095377611, 4620.260758278676, 4694.201985662428, 4838.879028024292, 4852.1508735971065, 4117.07365842686, 4120.938185927213, 4124.752514849119, 4139.586041395866, 4143.08852052548, 6286.49708023357, 6369.904451433232, 6379.36206379363, 6385.489160867166, 6394.840128996812, 4642.442906851199, 5722.170285308607, 5733.397993210736, 5736.82789516311, 5756.6546007239285, 6776.322367763233, 6992.545484543489, 7005.404540614054, 7011.298870112999, 7013.964930175316, 4605.953940460619, 4706.176419126673, 4706.22347312693, 4714.033623107255, 4730.692504987165, 7374.410047196211, 7434.921936319258, 7436.144843342977, 7441.479096463249, 7444.255574442566, 4781.067691800135, 4787.0904528139545, 4790.592799612003, 4793.209120495079, 4811.0, 4851.805927762921, 4852.393450818648, 4860.441049279308, 4863.076015557032, 4866.732329721828, 4769.713817170981, 5751.309842818889, 5754.942450575524, 5756.6685492375655, 5767.423257674199, 6771.221309549464, 7051.89422158668, 7084.822879427952, 7086.858262834715, 7093.787186384417, 1661.6925868714327, 1674.5646132005522, 1680.6302613425028, 1688.8902221355572, 1690.1042447502844, 1018.3075508654039, 1061.8035663402295, 1080.1844607321423, 1091.3124731419314, 1095.5727266366123, 6387.169667943138, 6451.806445806634, 6586.506012049115, 6597.274299827015, 6698.96650516754, 7342.816429589303, 7345.338919497271, 7358.521696089844, 7358.889616655754, 7363.48455608113, 6638.0, 6670.933290667128, 6673.232578253466, 6683.532152749358, 6685.665716714242, 3826.712996525216, 3873.012381842668, 3879.553851307079, 3880.708946828999, 3883.3786594145367, 6968.44252459802, 7340.96329518349, 7405.852092925606, 7661.808454788675, 7692.423068269902, 7534.849303013909, 7592.202818704067, 7595.202503737139, 7616.619169041525, 7617.619119044026, 7643.617819109134, 7647.923520764833, 7662.272084151997, 7666.54000759945, 7677.385809135256, 7136.571805691675, 7267.709291628275, 7372.631368431665, 7373.59445230496, 7404.888926665996, 7158.463115266271, 7206.56760594366, 7233.891491627524, 7244.818879528054, 7246.166690830411, 7620.885686714814, 7647.311741943252, 7649.005629268159, 7661.0, 7664.770056898302, 4590.88522786933, 4636.188666983332, 4656.837010704609, 4665.790039448165, 4666.719996800203, 7031.0, 7282.817929551592, 7335.669894854637, 7344.596047217455, 7347.926520734938, 5929.407059294036, 6071.72493776312, 6653.866922661423, 6903.654817259217, 7047.330503602149, 7073.328033836778, 7074.504784665024, 7083.185433675193, 7088.610126443095, 7114.822129446598, 6908.723651053901, 6917.757878474229, 6924.792256232321, 6925.722971081302, 6933.168019837652, 6707.798766037026, 6973.75591854303, 7054.470914681522, 7059.082319298458, 7082.858342833218, 6600.471962243105, 6759.966200168968, 6765.560238584476, 6900.654967251517, 6923.446124310144], "unit": "score", "name": "prototype.html", "value": 5168.500175258342}, {"lowerIsBetter": false, "replicates": [692.7332976803931, 698.744958090297, 719.2950908110049, 734.823642325842, 789.0491957191584, 15141.621459463518, 15332.61668458289, 15365.154916479603, 15403.614909627266, 15452.922735386335, 14059.5079172229, 14279.0, 14288.642783930381, 14291.642708932282, 14300.642483937881, 784.9568273744942, 790.2729488870253, 958.396210387455, 986.2011770465909, 986.6743974488437, 962.302330810164, 963.6868017894177, 968.6464440479244, 970.8252514547379, 991.3457118301923, 1016.9288149829515, 1017.6285655735677, 1019.7960407918409, 1022.427440633244, 1040.5577629507468, 834.6494472321609, 1026.7484466305766, 1035.3270374256717, 1049.171154787717, 1050.889656586055, 770.6955752477796, 776.2741836382944, 778.0119248554304, 778.0585491555225, 779.6413649721135, 15324.770128448083, 15387.4614388497, 15415.845841541553, 15441.0735355879, 15485.922570387189, 12807.359632018362, 12862.549810756578, 13090.149140305944, 13093.41079651418, 13141.342932853318, 10109.39343639384, 10249.897501024969, 10258.589656413735, 10433.373997560095, 10679.733006674818, 3547.0954906498678, 3580.2481478889463, 3580.7314451416246, 3600.837962291703, 3808.7143464240016, 82232.17767822245, 83470.0, 83530.0, 84234.0, 84277.57861210716, 9464.0, 9796.314258001872, 9800.705978820646, 9847.852282215772, 10651.094656954245, 22632.434189145075, 22658.66012009821, 22661.0, 22691.88654056752, 22720.20479283217, 6565.901511477332, 6718.361755633207, 6783.457323414115, 6864.828379290558, 6937.7224911004005, 4092.6112019358116, 4109.0, 4112.794360281974, 4139.5653456387245, 4247.893802654959, 3748.8687895923513, 3787.7348585599293, 3801.9239615208094, 3805.6955443564448, 3811.904702382463, 617.3209469583422, 629.6002038705456, 631.4916492223712, 640.1230314469113, 640.38203133975, 569.3822202909806, 570.5378643298941, 579.5276849367751, 631.7346714379971, 633.2259731997256, 6560.737570497222, 8166.183381661786, 8232.17678232172, 8336.041355244104, 8363.540005299767, 553.5156737854378, 565.7256230728094, 578.1991941161438, 601.8405122642504, 631.8009826904511, 3775.1505911169766, 3803.581606023335, 3821.4458903458844, 3880.9223815523533, 3897.279003384383, 91696.0, 93682.53158734163, 102690.48654756679, 102700.0, 107498.0, 80574.0, 81354.77967830487, 82205.58897205476, 83048.0, 83337.0, 65216.02175967364, 65329.0, 65409.67295163494, 65667.99999999904, 65802.34197657963, 8265.421420500506, 8347.415680902279, 8401.579921003804, 8446.118041890879, 8640.740777776677, 48278.75860620674, 49309.0, 50077.0, 51447.48552514427, 51663.0, 66475.0, 69756.0, 70580.23549411306, 70913.0, 72498.91251631125, 10727.517261723397, 10970.177236707119, 11047.116230701364, 11283.323000619988, 11806.704832379259, 9707.9029209707, 11689.824652630388, 11728.41357932117, 11948.880511194777, 12353.3823308836, 10979.39613321275, 11167.983254005767, 11494.310341379543, 11585.768284634429, 11650.76698466009, 11178.608748693756, 11450.312981221154, 11459.968602825786, 11554.59558915434, 11608.6517404478, 10609.045185933302, 10928.071113955402, 11182.888171118184, 11271.323720576791, 11277.7744445109, 9192.95403522978, 9488.478133702436, 9517.428954262767, 9659.547850951636, 9803.068708472683, 9099.863502047607, 9150.40522366044, 9528.761780955532, 9553.570089346134, 9610.903890961281], "unit": "score", "name": "yui.html", "value": 7020.404711152978}], "name": "dromaeo_css", "value": 7692.995945300424}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 110.0, 112.0, 111.0, 113.0, 112.0, 120.0, 114.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.4}, {"lowerIsBetter": true, "replicates": [159.0, 140.0, 179.0, 169.0, 137.0, 175.0, 158.0, 111.0, 190.0, 145.0], "unit": "score", "name": "audio-beat-detection", "value": 156.3}, {"lowerIsBetter": true, "replicates": [189.0, 185.0, 183.0, 186.0, 184.0, 186.0, 185.0, 187.0, 197.0, 256.0], "unit": "score", "name": "audio-dft", "value": 193.8}, {"lowerIsBetter": true, "replicates": [79.0, 71.0, 73.0, 117.0, 56.0, 59.0, 56.0, 58.0, 123.0, 55.0], "unit": "score", "name": "audio-fft", "value": 74.7}, {"lowerIsBetter": true, "replicates": [92.0, 95.0, 129.0, 82.0, 78.0, 85.0, 120.0, 77.0, 77.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 95.3}, {"lowerIsBetter": true, "replicates": [89.0, 85.0, 88.0, 86.0, 88.0, 85.0, 89.0, 84.0, 88.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.6}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 94.0, 93.0, 107.0, 147.0, 94.0, 94.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 100.0}, {"lowerIsBetter": true, "replicates": [83.0, 86.0, 84.0, 84.0, 84.0, 86.0, 84.0, 84.0, 83.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 84.4}, {"lowerIsBetter": true, "replicates": [54.0, 59.0, 56.0, 58.0, 58.0, 57.0, 58.0, 68.0, 51.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [64.0, 69.0, 61.0, 73.0, 71.0, 70.0, 66.0, 65.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.2}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 82.0, 82.0, 101.0, 78.0, 69.0, 95.0, 95.0, 108.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.1}, {"lowerIsBetter": true, "replicates": [130.0, 145.0, 135.0, 129.0, 117.0, 115.0, 116.0, 117.0, 118.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [134.0, 123.0, 134.0, 134.0, 131.0, 124.0, 136.0, 131.0, 130.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.0}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 65.0, 65.0, 67.0, 64.0, 65.0, 65.0, 65.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1435.5999999999997}]} 05:26:10 INFO - Return code: 0 05:26:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:10 INFO - # TBPL SUCCESS # 05:26:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:10 INFO - 2016-04-07 05:26:10.411 screenresolution[1968:20364] starting screenresolution argv=screenresolution get 05:26:10 INFO - 2016-04-07 05:26:10.414 screenresolution[1968:20364] Display 0: 1600x1200x32@60 05:26:10 INFO - 2016-04-07 05:26:10.421 screenresolution[1969:20367] starting screenresolution argv=screenresolution list 05:26:10 INFO - Available Modes on Display 0 05:26:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:10 INFO - Intel Iris: 05:26:10 INFO - Chipset Model: Intel Iris 05:26:10 INFO - Type: GPU 05:26:10 INFO - Bus: Built-In 05:26:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:10 INFO - Vendor: Intel (0x8086) 05:26:10 INFO - Device ID: 0x0a2e 05:26:10 INFO - Revision ID: 0x0009 05:26:10 INFO - Displays: 05:26:10 INFO - Display: 05:26:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:10 INFO - Display Serial Number: 1600x1200 60 05:26:10 INFO - Main Display: Yes 05:26:10 INFO - Mirror: Off 05:26:10 INFO - Online: Yes 05:26:10 INFO - Rotation: Supported 05:26:10 INFO - Return code: 0 05:26:10 INFO - Running post-action listener: _resource_record_post_action 05:26:10 INFO - Running post-run listener: _resource_record_post_run 05:26:11 INFO - Total resource usage - Wall time: 1202s; CPU: 52.0%; Read bytes: 1093632; Write bytes: 660774912; Read time: 8; Write time: 1831 05:26:11 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361560576; Write bytes: 362832896; Read time: 28299; Write time: 669 05:26:11 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 1093632; Write bytes: 296410112; Read time: 8; Write time: 1156 05:26:11 INFO - Running post-run listener: _upload_blobber_files 05:26:11 INFO - Blob upload gear active. 05:26:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:11 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:26:11 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:26:11 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:26:12 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:12 INFO - (blobuploader) - INFO - Done attempting. 05:26:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:14 INFO - (blobuploader) - INFO - Done attempting. 05:26:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:14 INFO - Return code: 0 05:26:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b33eb755b4a8d3f078c1baa27044fd215325ad183adaa3f9cbdfc149dae8087c0a647948afc80bca3888df8c4f0242a09464e5f8090b31de1fd6c1af3eabd82a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea5bcb3f7b498b70c5a05c2de147b6b4389c81d1dda826b173c18813d8c40784382e7b1924b93512c0971945f43046c931907a40278385413d619de13e5aaf26"} 05:26:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:14 INFO - Contents: 05:26:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b33eb755b4a8d3f078c1baa27044fd215325ad183adaa3f9cbdfc149dae8087c0a647948afc80bca3888df8c4f0242a09464e5f8090b31de1fd6c1af3eabd82a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea5bcb3f7b498b70c5a05c2de147b6b4389c81d1dda826b173c18813d8c40784382e7b1924b93512c0971945f43046c931907a40278385413d619de13e5aaf26"} 05:26:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:14 INFO - Copying logs to upload dir... 05:26:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.448400 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 05:26:14.258269) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:14.261427) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b33eb755b4a8d3f078c1baa27044fd215325ad183adaa3f9cbdfc149dae8087c0a647948afc80bca3888df8c4f0242a09464e5f8090b31de1fd6c1af3eabd82a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea5bcb3f7b498b70c5a05c2de147b6b4389c81d1dda826b173c18813d8c40784382e7b1924b93512c0971945f43046c931907a40278385413d619de13e5aaf26"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011204 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b33eb755b4a8d3f078c1baa27044fd215325ad183adaa3f9cbdfc149dae8087c0a647948afc80bca3888df8c4f0242a09464e5f8090b31de1fd6c1af3eabd82a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea5bcb3f7b498b70c5a05c2de147b6b4389c81d1dda826b173c18813d8c40784382e7b1924b93512c0971945f43046c931907a40278385413d619de13e5aaf26"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:14.299421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:14.299778) ========= 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.E7M7FXzozx/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.O8QrRFyB65/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005282 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:14.367403) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:14.367724) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:14.368188) ========= ========= Total master_lag: 0.30 =========