builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0099 starttime: 1455801394.25 results: success (0) buildid: 20160218030349 builduid: 061bfcfea0dc44ff98f6ed9811c36951 revision: 1150ac4755c7bb35df4fc7504f6f1b6c257f400e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.245503) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.246027) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.246388) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.005587 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.270359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.270679) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.287941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.288262) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.005564 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.339954) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.340685) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.341453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.342045) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-02-18 05:16:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2016-02-18 05:16:34 (13.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.456549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.457112) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.025016 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:34.509927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-02-18 05:16:34.510685) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1150ac4755c7bb35df4fc7504f6f1b6c257f400e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1150ac4755c7bb35df4fc7504f6f1b6c257f400e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-02-18 05:16:34,584 truncating revision to first 12 chars 2016-02-18 05:16:34,584 Setting DEBUG logging. 2016-02-18 05:16:34,584 attempt 1/10 2016-02-18 05:16:34,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1150ac4755c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-18 05:16:35,168 unpacking tar archive at: mozilla-central-1150ac4755c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.826675 ========= master_lag: 2.70 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-02-18 05:16:38.039423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:38.039835) ========= echo 1150ac4755c7bb35df4fc7504f6f1b6c257f400e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1150ac4755c7bb35df4fc7504f6f1b6c257f400e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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 1150ac4755c7bb35df4fc7504f6f1b6c257f400e program finished with exit code 0 elapsedTime=0.005985 script_repo_revision: '1150ac4755c7bb35df4fc7504f6f1b6c257f400e' ========= master_lag: 0.14 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 05:16:38.190109) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-02-18 05:16:38.190590) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-02-18 05:16:49.002706) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-18 05:16:49.003054) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:16:49 INFO - MultiFileLogger online at 20160218 05:16:49 in /builds/slave/test 05:16:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:16:49 INFO - {'append_to_log': False, 05:16:49 INFO - 'base_work_dir': '/builds/slave/test', 05:16:49 INFO - 'blob_upload_branch': 'Firefox', 05:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:16:49 INFO - 'branch': 'Firefox', 05:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:16:49 INFO - 'config_files': ('talos/mac_config.py',), 05:16:49 INFO - 'default_actions': ('clobber', 05:16:49 INFO - 'read-buildbot-config', 05:16:49 INFO - 'download-and-extract', 05:16:49 INFO - 'populate-webroot', 05:16:49 INFO - 'create-virtualenv', 05:16:49 INFO - 'install', 05:16:49 INFO - 'run-tests'), 05:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:16:49 INFO - 'download_minidump_stackwalk': True, 05:16:49 INFO - 'download_symbols': 'ondemand', 05:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:16:49 INFO - 'installer_path': 'installer.exe', 05:16:49 INFO - 'log_level': 'info', 05:16:49 INFO - 'log_name': 'talos', 05:16:49 INFO - 'log_to_console': True, 05:16:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:16:49 INFO - 'opt_config_files': (), 05:16:49 INFO - 'pip_index': False, 05:16:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:49 INFO - 'cmd': ('bash', 05:16:49 INFO - '-c', 05:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:49 INFO - 'enabled': True, 05:16:49 INFO - 'halt_on_failure': False, 05:16:49 INFO - 'name': 'check_screen_resolution'},), 05:16:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:49 INFO - 'cmd': ('bash', 05:16:49 INFO - '-c', 05:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:49 INFO - 'enabled': True, 05:16:49 INFO - 'halt_on_failure': False, 05:16:49 INFO - 'name': 'check_screen_resolution'},), 05:16:49 INFO - 'run_cmd_checks_enabled': True, 05:16:49 INFO - 'sps_profile': False, 05:16:49 INFO - 'sps_profile_interval': 0, 05:16:49 INFO - 'suite': 'dromaeojs', 05:16:49 INFO - 'system_bits': '32', 05:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:16:49 INFO - 'title': 't-yosemite-r7-0099', 05:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:16:49 INFO - 'use_talos_json': True, 05:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:16:49 INFO - 'work_dir': 'build'} 05:16:49 INFO - ##### 05:16:49 INFO - ##### Running clobber step. 05:16:49 INFO - ##### 05:16:49 INFO - Running pre-action listener: _resource_record_pre_action 05:16:49 INFO - Running main action method: clobber 05:16:49 INFO - rmtree: /builds/slave/test/build 05:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:16:51 INFO - Running post-action listener: _resource_record_post_action 05:16:51 INFO - ##### 05:16:51 INFO - ##### Running read-buildbot-config step. 05:16:51 INFO - ##### 05:16:51 INFO - Running pre-action listener: _resource_record_pre_action 05:16:51 INFO - Running main action method: read_buildbot_config 05:16:51 INFO - Using buildbot properties: 05:16:51 INFO - { 05:16:51 INFO - "project": "", 05:16:51 INFO - "product": "firefox", 05:16:51 INFO - "script_repo_revision": "production", 05:16:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:16:51 INFO - "repository": "", 05:16:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:16:51 INFO - "buildid": "20160218030349", 05:16:51 INFO - "stage_platform": "macosx64", 05:16:51 INFO - "basedir": "/builds/slave/test", 05:16:51 INFO - "nightly_build": "True", 05:16:51 INFO - "buildnumber": 144, 05:16:51 INFO - "slavename": "t-yosemite-r7-0099", 05:16:51 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:16:51 INFO - "platform": "yosemite_r7", 05:16:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:16:51 INFO - "branch": "mozilla-central", 05:16:51 INFO - "repo_path": "mozilla-central", 05:16:51 INFO - "revision": "1150ac4755c7bb35df4fc7504f6f1b6c257f400e", 05:16:51 INFO - "pgo_build": "False", 05:16:51 INFO - "builduid": "061bfcfea0dc44ff98f6ed9811c36951", 05:16:51 INFO - "slavebuilddir": "test" 05:16:51 INFO - } 05:16:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 05:16:51 INFO - Running post-action listener: _resource_record_post_action 05:16:51 INFO - ##### 05:16:51 INFO - ##### Running download-and-extract step. 05:16:51 INFO - ##### 05:16:51 INFO - Running pre-action listener: _resource_record_pre_action 05:16:51 INFO - Running main action method: download_and_extract 05:16:51 INFO - mkdir: /builds/slave/test/build/tests 05:16:51 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:16:51 INFO - https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:16:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/test_packages.json 05:16:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/test_packages.json 05:16:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:16:52 INFO - Downloaded 1277 bytes. 05:16:52 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:16:52 INFO - Using the following test package requirements: 05:16:52 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:16:52 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:16:52 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:16:52 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'jsshell-mac.zip'], 05:16:52 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:16:52 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:16:52 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:16:52 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:16:52 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:16:52 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:16:52 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:16:52 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:16:52 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:52 INFO - https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:16:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:53 INFO - Downloaded 17807431 bytes. 05:16:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:16:56 INFO - Return code: 0 05:16:56 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:56 INFO - https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:16:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:16:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:59 INFO - Downloaded 17807431 bytes. 05:16:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:02 INFO - Return code: 0 05:17: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:17:02 INFO - https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:17:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:17:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:17:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:17:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:17:03 INFO - Downloaded 10707071 bytes. 05:17:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:17:03 INFO - Return code: 0 05:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:03 INFO - https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:17:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:17:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 05:17:08 INFO - Downloaded 111469950 bytes. 05:17:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:17:08 INFO - mkdir: /builds/slave/test/properties 05:17:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:08 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:08 INFO - Contents: 05:17:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:17:09 INFO - Running post-action listener: _resource_record_post_action 05:17:09 INFO - Running post-action listener: set_extra_try_arguments 05:17:09 INFO - ##### 05:17:09 INFO - ##### Running populate-webroot step. 05:17:09 INFO - ##### 05:17:09 INFO - Running pre-action listener: _resource_record_pre_action 05:17:09 INFO - Running main action method: populate_webroot 05:17:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:09 INFO - '--tppagecycles', 05:17:09 INFO - '7'], 05:17:09 INFO - 'tests': ['tsvgm']}}, 05:17:09 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:17:09 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:09 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:17:09 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'tests': ['damp', 'tps']}, 05:17:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['damp', 'tps']}, 05:17:09 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:17:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:09 INFO - 'other': {'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'other_l64': {'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'other_nol64': {'tests': ['a11yr', 05:17:09 INFO - 'ts_paint', 05:17:09 INFO - 'tpaint', 05:17:09 INFO - 'sessionrestore', 05:17:09 INFO - 'sessionrestore_no_auto_restore']}, 05:17:09 INFO - 'svgr': {'tests': ['tsvgx', 05:17:09 INFO - 'tsvgr_opacity', 05:17:09 INFO - 'tart', 05:17:09 INFO - 'tscrollx', 05:17:09 INFO - 'cart']}, 05:17:09 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['tsvgx', 05:17:09 INFO - 'tsvgr_opacity', 05:17:09 INFO - 'tart', 05:17:09 INFO - 'tscrollx', 05:17:09 INFO - 'cart']}, 05:17:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'tests': ['tp5o']}, 05:17:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'talos_options': ['--e10s'], 05:17:09 INFO - 'tests': ['tp5o']}, 05:17:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'talos_options': ['--xperf_path', 05:17:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:09 INFO - 'tests': ['tp5n']}, 05:17:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:09 INFO - 'talos_options': ['--e10s', 05:17:09 INFO - '--xperf_path', 05:17:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:09 INFO - 'tests': ['tp5n']}}, 05:17:09 INFO - 'talos.zip': {'path': '', 05:17:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:09 INFO - Running post-action listener: _resource_record_post_action 05:17:09 INFO - ##### 05:17:09 INFO - ##### Running create-virtualenv step. 05:17:09 INFO - ##### 05:17:09 INFO - Running pre-action listener: _resource_record_pre_action 05:17:09 INFO - Running main action method: create_virtualenv 05:17:09 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:09 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:17:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:09 INFO - Using real prefix '/tools/python27' 05:17:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:09 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:12 INFO - Installing pip.................done. 05:17:12 INFO - Return code: 0 05:17:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:12 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:12 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:17:12 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:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:12 INFO - 'HOME': '/Users/cltbld', 05:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:12 INFO - 'LOGNAME': 'cltbld', 05:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:12 INFO - 'MOZ_NO_REMOTE': '1', 05:17:12 INFO - 'NO_EM_RESTART': '1', 05:17:12 INFO - 'PAGER': '/bin/cat', 05:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:12 INFO - 'PWD': '/builds/slave/test', 05:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:12 INFO - 'SHELL': '/bin/bash', 05:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:12 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:12 INFO - 'USER': 'cltbld', 05:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:12 INFO - 'XPC_FLAGS': '0x0', 05:17:12 INFO - 'XPC_SERVICE_NAME': '0', 05:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:13 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:17:13 INFO - Cleaning up... 05:17:13 INFO - Return code: 0 05:17:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:13 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:17:13 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:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:13 INFO - 'HOME': '/Users/cltbld', 05:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:13 INFO - 'LOGNAME': 'cltbld', 05:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:13 INFO - 'MOZ_NO_REMOTE': '1', 05:17:13 INFO - 'NO_EM_RESTART': '1', 05:17:13 INFO - 'PAGER': '/bin/cat', 05:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:13 INFO - 'PWD': '/builds/slave/test', 05:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:13 INFO - 'SHELL': '/bin/bash', 05:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:13 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:13 INFO - 'USER': 'cltbld', 05:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:13 INFO - 'XPC_FLAGS': '0x0', 05:17:13 INFO - 'XPC_SERVICE_NAME': '0', 05:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:13 INFO - Downloading/unpacking psutil>=0.7.1 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:16 INFO - Installing collected packages: psutil 05:17:16 INFO - Running setup.py install for psutil 05:17:16 INFO - building 'psutil._psutil_osx' extension 05:17:16 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:17:16 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:17:16 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:17:17 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:17:17 INFO - building 'psutil._psutil_posix' extension 05:17:17 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:17:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:17 INFO - ^ 05:17:17 INFO - 1 warning generated. 05:17:17 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:17:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:17 INFO - Successfully installed psutil 05:17:17 INFO - Cleaning up... 05:17:17 INFO - Return code: 0 05:17:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:17 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:17 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:17:17 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:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:17 INFO - 'HOME': '/Users/cltbld', 05:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:17 INFO - 'LOGNAME': 'cltbld', 05:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:17 INFO - 'MOZ_NO_REMOTE': '1', 05:17:17 INFO - 'NO_EM_RESTART': '1', 05:17:17 INFO - 'PAGER': '/bin/cat', 05:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:17 INFO - 'PWD': '/builds/slave/test', 05:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:17 INFO - 'SHELL': '/bin/bash', 05:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:17 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:17 INFO - 'USER': 'cltbld', 05:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:17 INFO - 'XPC_FLAGS': '0x0', 05:17:17 INFO - 'XPC_SERVICE_NAME': '0', 05:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:17:17 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:17:17 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:17:17 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:17:17 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:17:17 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:17:17 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:17:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:20 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:17:20 INFO - Installing collected packages: mozsystemmonitor 05:17:20 INFO - Running setup.py install for mozsystemmonitor 05:17:20 INFO - Successfully installed mozsystemmonitor 05:17:20 INFO - Cleaning up... 05:17:20 INFO - Return code: 0 05:17:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17: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:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:20 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:20 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:17:20 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:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:20 INFO - 'HOME': '/Users/cltbld', 05:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:20 INFO - 'LOGNAME': 'cltbld', 05:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:20 INFO - 'MOZ_NO_REMOTE': '1', 05:17:20 INFO - 'NO_EM_RESTART': '1', 05:17:20 INFO - 'PAGER': '/bin/cat', 05:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:20 INFO - 'PWD': '/builds/slave/test', 05:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:20 INFO - 'SHELL': '/bin/bash', 05:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:20 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:20 INFO - 'USER': 'cltbld', 05:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:20 INFO - 'XPC_FLAGS': '0x0', 05:17:20 INFO - 'XPC_SERVICE_NAME': '0', 05:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:20 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:17:20 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:17:20 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:17:20 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:17:20 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:17:20 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:17:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:23 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:17:23 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:17:23 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:17:23 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:17:23 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:17:23 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:17:24 INFO - Downloading docopt-0.6.1.tar.gz 05:17:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:24 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:24 INFO - Running setup.py install for blobuploader 05:17:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:24 INFO - Running setup.py install for requests 05:17:24 INFO - Running setup.py install for docopt 05:17:24 INFO - Successfully installed blobuploader requests docopt 05:17:24 INFO - Cleaning up... 05:17:25 INFO - Return code: 0 05:17:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:25 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:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 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:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:25 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:17:25 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:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:25 INFO - 'HOME': '/Users/cltbld', 05:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:25 INFO - 'LOGNAME': 'cltbld', 05:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:25 INFO - 'MOZ_NO_REMOTE': '1', 05:17:25 INFO - 'NO_EM_RESTART': '1', 05:17:25 INFO - 'PAGER': '/bin/cat', 05:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:25 INFO - 'PWD': '/builds/slave/test', 05:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:25 INFO - 'SHELL': '/bin/bash', 05:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:25 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:25 INFO - 'USER': 'cltbld', 05:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:25 INFO - 'XPC_FLAGS': '0x0', 05:17:25 INFO - 'XPC_SERVICE_NAME': '0', 05:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-5GliTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-JR5mH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-z79Mnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-LnsKPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-n8oST9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-jESCgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:25 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-r05JBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-f5pTBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-Hz1viR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-dDGUvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-N6EwEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-0UlurD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-RiqKca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-Mh0A8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-5RJQNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:26 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zenjjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:27 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-0JUkFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:27 INFO - Running setup.py install for manifestparser 05:17:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozcrash 05:17:27 INFO - Running setup.py install for mozdebug 05:17:27 INFO - Running setup.py install for mozdevice 05:17:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozfile 05:17:27 INFO - Running setup.py install for mozhttpd 05:17:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozinfo 05:17:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Running setup.py install for mozInstall 05:17:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Running setup.py install for mozleak 05:17:28 INFO - Running setup.py install for mozlog 05:17:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Running setup.py install for moznetwork 05:17:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Running setup.py install for mozprocess 05:17:28 INFO - Running setup.py install for mozprofile 05:17:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozrunner 05:17:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Running setup.py install for mozscreenshot 05:17:29 INFO - Running setup.py install for moztest 05:17:29 INFO - Running setup.py install for mozversion 05:17:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:29 INFO - Cleaning up... 05:17:29 INFO - Return code: 0 05:17:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:30 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:17:30 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:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:30 INFO - 'HOME': '/Users/cltbld', 05:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:30 INFO - 'LOGNAME': 'cltbld', 05:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:30 INFO - 'MOZ_NO_REMOTE': '1', 05:17:30 INFO - 'NO_EM_RESTART': '1', 05:17:30 INFO - 'PAGER': '/bin/cat', 05:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:30 INFO - 'PWD': '/builds/slave/test', 05:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:30 INFO - 'SHELL': '/bin/bash', 05:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:30 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:30 INFO - 'USER': 'cltbld', 05:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:30 INFO - 'XPC_FLAGS': '0x0', 05:17:30 INFO - 'XPC_SERVICE_NAME': '0', 05:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:30 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-bxRVdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:30 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:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:30 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-JLqk8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:30 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-9XKc1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-UXtot4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-ZBKoQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-3X1S4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-NJ9DCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-QgAw_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-muICbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-E65fNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-F3Cztx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:31 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:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-9cSxDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:32 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:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-pJYYJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:32 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:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-zbSgxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:32 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:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-aI7Eby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:32 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:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AreKf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:32 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:17:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AtqSK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:32 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:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:32 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:17:32 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:17:32 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:17:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:32 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:17:32 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:17:32 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:17:32 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:17:32 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:17:32 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:17:35 INFO - Downloading blessings-1.6.tar.gz 05:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:35 INFO - Installing collected packages: blessings 05:17:35 INFO - Running setup.py install for blessings 05:17:35 INFO - Successfully installed blessings 05:17:35 INFO - Cleaning up... 05:17:35 INFO - Return code: 0 05:17:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:35 INFO - Reading from file tmpfile_stdout 05:17:35 INFO - Current package versions: 05:17:35 INFO - blessings == 1.6 05:17:35 INFO - blobuploader == 1.2.4 05:17:35 INFO - docopt == 0.6.1 05:17:35 INFO - manifestparser == 1.1 05:17:35 INFO - mozInstall == 1.12 05:17:35 INFO - mozcrash == 0.16 05:17:35 INFO - mozdebug == 0.1 05:17:35 INFO - mozdevice == 0.48 05:17:35 INFO - mozfile == 1.2 05:17:35 INFO - mozhttpd == 0.7 05:17:35 INFO - mozinfo == 0.9 05:17:35 INFO - mozleak == 0.1 05:17:35 INFO - mozlog == 3.1 05:17:35 INFO - moznetwork == 0.27 05:17:35 INFO - mozprocess == 0.22 05:17:35 INFO - mozprofile == 0.28 05:17:35 INFO - mozrunner == 6.11 05:17:35 INFO - mozscreenshot == 0.1 05:17:35 INFO - mozsystemmonitor == 0.0 05:17:35 INFO - moztest == 0.7 05:17:35 INFO - mozversion == 1.4 05:17:35 INFO - psutil == 3.1.1 05:17:35 INFO - requests == 1.2.3 05:17:35 INFO - wsgiref == 0.1.2 05:17:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:35 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:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:35 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:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:35 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:35 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:17:35 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:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:35 INFO - 'HOME': '/Users/cltbld', 05:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:35 INFO - 'LOGNAME': 'cltbld', 05:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:35 INFO - 'MOZ_NO_REMOTE': '1', 05:17:35 INFO - 'NO_EM_RESTART': '1', 05:17:35 INFO - 'PAGER': '/bin/cat', 05:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:35 INFO - 'PWD': '/builds/slave/test', 05:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:35 INFO - 'SHELL': '/bin/bash', 05:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:35 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:35 INFO - 'USER': 'cltbld', 05:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:35 INFO - 'XPC_FLAGS': '0x0', 05:17:35 INFO - 'XPC_SERVICE_NAME': '0', 05:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 INFO - Cleaning up... 05:17:36 INFO - Return code: 0 05:17:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17: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:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17: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', 'jsonschema'] in /builds/slave/test/build 05:17: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 jsonschema 05:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:36 INFO - 'HOME': '/Users/cltbld', 05:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:36 INFO - 'LOGNAME': 'cltbld', 05:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:36 INFO - 'MOZ_NO_REMOTE': '1', 05:17:36 INFO - 'NO_EM_RESTART': '1', 05:17:36 INFO - 'PAGER': '/bin/cat', 05:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:36 INFO - 'PWD': '/builds/slave/test', 05:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:36 INFO - 'SHELL': '/bin/bash', 05:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:36 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:36 INFO - 'USER': 'cltbld', 05:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:36 INFO - 'XPC_FLAGS': '0x0', 05:17:36 INFO - 'XPC_SERVICE_NAME': '0', 05:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:36 INFO - Downloading/unpacking jsonschema 05:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:36 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:17:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:38 INFO - Downloading/unpacking functools32 (from jsonschema) 05:17:38 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:17:38 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:17:38 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:17:38 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:17:38 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:17:38 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:17:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:39 INFO - warning: no files found matching '*.txt' 05:17:39 INFO - no previously-included directories found matching 'build' 05:17:39 INFO - no previously-included directories found matching 'dist' 05:17:39 INFO - no previously-included directories found matching '.git*' 05:17:39 INFO - Installing collected packages: jsonschema, functools32 05:17:39 INFO - Running setup.py install for functools32 05:17:39 INFO - warning: no files found matching '*.txt' 05:17:39 INFO - no previously-included directories found matching 'build' 05:17:39 INFO - no previously-included directories found matching 'dist' 05:17:39 INFO - no previously-included directories found matching '.git*' 05:17:39 INFO - Successfully installed jsonschema functools32 05:17:39 INFO - Cleaning up... 05:17:39 INFO - Return code: 0 05:17:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:17: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:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:39 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 0x103c23a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c2b4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc924c7cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, '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.huIjz2sOpj/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.fqhJCKcR6h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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:17:39 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:17:39 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:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:39 INFO - 'HOME': '/Users/cltbld', 05:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:39 INFO - 'LOGNAME': 'cltbld', 05:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:39 INFO - 'MOZ_NO_REMOTE': '1', 05:17:39 INFO - 'NO_EM_RESTART': '1', 05:17:39 INFO - 'PAGER': '/bin/cat', 05:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:39 INFO - 'PWD': '/builds/slave/test', 05:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:39 INFO - 'SHELL': '/bin/bash', 05:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:17:39 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:39 INFO - 'USER': 'cltbld', 05:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:39 INFO - 'XPC_FLAGS': '0x0', 05:17:39 INFO - 'XPC_SERVICE_NAME': '0', 05:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:39 INFO - Downloading/unpacking flake8 05:17:39 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:17:39 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:17:39 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:17:39 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:17:39 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:17:39 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:17:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:17:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:17: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:17: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:17: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:17: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:17: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:17: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:17:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:17:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:17: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:17: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:17: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:17: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:17: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:17: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:17:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:17: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:17: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:17: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:17: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:17: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:17: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:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:17:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:43 INFO - no previously-included directories found matching 'docs/_build' 05:17:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:17:43 INFO - Running setup.py install for pep8 05:17:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:43 INFO - no previously-included directories found matching 'docs/_build' 05:17:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:17:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:17:43 INFO - Cleaning up... 05:17:43 INFO - Return code: 0 05:17:43 INFO - Running post-action listener: _resource_record_post_action 05:17:43 INFO - Running post-action listener: _start_resource_monitoring 05:17:43 INFO - Starting resource monitoring. 05:17:43 INFO - ##### 05:17:43 INFO - ##### Running install step. 05:17:43 INFO - ##### 05:17:43 INFO - Running pre-action listener: _resource_record_pre_action 05:17:43 INFO - Running main action method: install 05:17:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:44 INFO - Reading from file tmpfile_stdout 05:17:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:44 INFO - Reading from file tmpfile_stdout 05:17:44 INFO - Output received: 05:17:44 INFO - Usage: mozinstall [options] installer 05:17:44 INFO - Options: 05:17:44 INFO - -h, --help show this help message and exit 05:17:44 INFO - -d DEST, --destination=DEST 05:17:44 INFO - Directory to install application into. [default: 05:17:44 INFO - "/builds/slave/test"] 05:17:44 INFO - --app=APP Application being installed. [default: firefox] 05:17:44 INFO - mkdir: /builds/slave/test/build/application 05:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:18 INFO - Reading from file tmpfile_stdout 05:18:18 INFO - Output received: 05:18:18 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:18 INFO - Running post-action listener: _resource_record_post_action 05:18:18 INFO - ##### 05:18:18 INFO - ##### Running run-tests step. 05:18:18 INFO - ##### 05:18:18 INFO - Running pre-action listener: _resource_record_pre_action 05:18:18 INFO - Running main action method: run_tests 05:18:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:18 INFO - 2016-02-18 05:18:18.772 screenresolution[1649:7596] starting screenresolution argv=screenresolution get 05:18:18 INFO - 2016-02-18 05:18:18.775 screenresolution[1649:7596] Display 0: 1600x1200x32@60 05:18:18 INFO - 2016-02-18 05:18:18.781 screenresolution[1650:7598] starting screenresolution argv=screenresolution list 05:18:18 INFO - Available Modes on Display 0 05:18:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:19 INFO - Intel Iris: 05:18:19 INFO - Chipset Model: Intel Iris 05:18:19 INFO - Type: GPU 05:18:19 INFO - Bus: Built-In 05:18:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:19 INFO - Vendor: Intel (0x8086) 05:18:19 INFO - Device ID: 0x0a2e 05:18:19 INFO - Revision ID: 0x0009 05:18:19 INFO - Displays: 05:18:19 INFO - Display: 05:18:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:19 INFO - Display Serial Number: 1600x1200 60 05:18:19 INFO - Main Display: Yes 05:18:19 INFO - Mirror: Off 05:18:19 INFO - Online: Yes 05:18:19 INFO - Rotation: Supported 05:18:19 INFO - Return code: 0 05:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:19 INFO - Python 2.7.3 05:18:19 INFO - Return code: 0 05:18:19 INFO - grabbing minidump binary from tooltool 05:18:19 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:18:19 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 0x103ac7d30>, '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 0x1038ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103abeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:19 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:18:19 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:18:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:19 INFO - Return code: 0 05:18:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:20 INFO - Return code: 0 05:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--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:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0099 --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:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.huIjz2sOpj/Render', 05:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:20 INFO - 'HOME': '/Users/cltbld', 05:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:20 INFO - 'LOGNAME': 'cltbld', 05:18:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:20 INFO - 'MOZ_NO_REMOTE': '1', 05:18:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:20 INFO - 'NO_EM_RESTART': '1', 05:18:20 INFO - 'PAGER': '/bin/cat', 05:18:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:20 INFO - 'PWD': '/builds/slave/test', 05:18:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:20 INFO - 'SHELL': '/bin/bash', 05:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fqhJCKcR6h/Listeners', 05:18:20 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 05:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:20 INFO - 'USER': 'cltbld', 05:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:20 INFO - 'XPC_FLAGS': '0x0', 05:18:20 INFO - 'XPC_SERVICE_NAME': '0', 05:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--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:18:20 INFO - mozversion application_buildid: 20160218030349 05:18:20 INFO - mozversion application_changeset: 1150ac4755c7bb35df4fc7504f6f1b6c257f400e 05:18:20 INFO - mozversion application_display_name: Nightly 05:18:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:20 INFO - mozversion application_name: Firefox 05:18:20 INFO - mozversion application_remotingname: firefox 05:18:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:18:20 INFO - mozversion application_vendor: Mozilla 05:18:20 INFO - mozversion application_version: 47.0a1 05:18:20 INFO - mozversion platform_buildid: 20160218030349 05:18:20 INFO - mozversion platform_changeset: 1150ac4755c7bb35df4fc7504f6f1b6c257f400e 05:18:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:18:20 INFO - mozversion platform_version: 47.0a1 05:18:20 INFO - using testdate: 1455801500 05:18:20 INFO - actual date: 1455801500 05:18:21 INFO - starting webserver on 'localhost:49274' 05:18:21 INFO - SUITE-START | Running 2 tests 05:18:21 INFO - TEST-START | dromaeo_css 05:18:21 INFO - operating with platform_type : mac_ 05:18:21 INFO - Initialising browser for dromaeo_css test... 05:18:21 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp2YIzRh/profile http://localhost:49274/getInfo.html) 05:18:23 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 05:18:23 INFO - PROCESS | 1671 | colorDepth:24 05:18:23 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 05:18:23 INFO - PROCESS | 1671 | __metrics 05:18:23 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:18:23 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:18:24 INFO - TEST-INFO | 1671: exit 0 05:18:24 INFO - Browser initialized. 05:18:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:24 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp2YIzRh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:36 INFO - PROCESS | 1675 | RSS: Main: 244752384 05:18:36 INFO - PROCESS | 1675 | 05:19:27 INFO - PROCESS | 1675 | 1455801567452 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:21:37 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:21:39 INFO - PROCESS | 1675 | RSS: Main: 425095168 05:21:39 INFO - PROCESS | 1675 | 05:24:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:24:42 INFO - PROCESS | 1675 | RSS: Main: 375689216 05:24:42 INFO - PROCESS | 1675 | 05:27:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 05:27:45 INFO - PROCESS | 1675 | RSS: Main: 381001728 05:27:45 INFO - PROCESS | 1675 | 05:30:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 05:30:48 INFO - PROCESS | 1675 | RSS: Main: 339718144 05:30:48 INFO - PROCESS | 1675 | 05:33:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 05:33:51 INFO - PROCESS | 1675 | RSS: Main: 342036480 05:33:51 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:36:54 INFO - PROCESS | 1675 | RSS: Main: 381960192 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | __start_tp_report 05:36:54 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 05:36:54 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 05:36:54 INFO - PROCESS | 1675 | |i|pagename|runs| 05:36:54 INFO - PROCESS | 1675 | |0;dojo.html;3215.3730022645577;3476.5132881396594;3505.8597656093757;3520.366334059868;3558.2171922177113;4773.856784296477;4812.614990800736;5709.229254050707;5728.828135155947;5740.6268592541455;5606.635568688032;5652.0391533439315;5665.036943719567;5668.234788303578;5669.036263835148;5145.845624631258;5155.994581056695;5183.50756678115;5213.739313034352;5249.4750524947585;5235.633505654606;5245.4754524547625;5263.526282634562;5270.39390470097;5276.076686579833;5255.343082114736;5260.552853007499;5275.973620131894;5278.181881808307;5289;5273.789048438058;5321.733913304358;5336.226247194135;5347.197920311945;5370.248165256867;5155.432902380735;5168.508991645787;5183.144781111112;5212.869678258074;5243.895122097537;5592.6085174037835;5610.102383618642;5618.213450116987;5628.774849006035;5652.180433837112;6271.686415679198;6286.842828929268;6288.402601752826;6296.716647750863;6301.243850737895;6151.861905547488;6173.536984726119;6262.405071518198;6316.589421687621;6328.430441260262;5822.010258256107;5831.416858314219;5831.766729330864;5881.411858814127;5907.172995780594;6258.217722784652;6271.811845644638;6274.152989346379;6290.905636415458;6302.65335406557;6046.909296360558;6062.302835173923;6070.0110418097265;6095.105866018796;6115.816525504242;5667.121596152603;6018;6173.475254603409;6234.127222188898;6347.746090156435;6213;6227.595206311576;6233.501319894398;6236.532260080512;6244.781432649836;6044.576879618385;6137.079438084323;6242.719077641426;6276.246850377984;6279.152314437584;4862.513748625109;4863.513648635179;4882.389701287339;4884.706917584886;4888.242322440027;4346.326319420495;4439.511653718088;4451.0430257494145;4477.28363461851;4478.955210447919;5419.647722897999;5662.68855212959;5677.120046392815;5685.857853553694;5764.682942438206;6349;6371.426571608577;6381.744730210832;6383.106365108889;6422.8715425691225;5556.083246264321;5683.772649094072;5685.772569097273;5722.9984752668115;5737.85655358611;5275.630705850554;5277.815276465382;5281.234221037928;5287.814926477555;5318.7074710891275;6275.1842260506755;6275.843103922347;6303.747850085945;6320.020396838498;6338.049292606146;6169.81490555284;6203.8138855834395;6217.129601855653;6240.719167637558;6323.1463752394675;6282.685865706605;6285.96857015712;6293.96853015732;6304.590201636879;6324.114623952558;6206.968965155145;6214.130021797043;6215.440610345091;6239.251289845066;6243.344448832989;6148.292946311231;6225.252969643671;6232.688365581794;6313.779017734358;6322.589031712924;5955.851103722442;6072.666003369857;6086.969565152146;6114.960456722279;6144.293406258337;5903.085021821625;6136.417040381157;6140.109684095871;6146.248319228998;6155.322914479314;5804.709764511673;5833.358330583547;5836.678982655825;5837.0368889132815;5838.211841401527;5903.114532820111;5953.1367951647635;5967.4629283362965;5972.850678733066;5980.880382392241;5818.2436283282905;5821.70891455417;5822.73797679097;5823.213866128019;5824.388439213991 05:36:54 INFO - PROCESS | 1675 | |1;ext.html;429.6777416937299;478.8539495453886;532.9067413202689;540.9856519026824;594.2779522879702;29030.419391612155;29546.704532954664;29574.704252957465;36629.63370366302;37380;40333.78998630046;40652.79673601628;40681.18637627245;40691;40718.79640601793;9466.242700583955;9662.227021838236;9671.129598336149;9674.322797404184;9677.322587418883;9483.288753343526;9541.856872146924;9617.759556011086;9636.662716804913;9648.565814538362;9652.420854748703;9739.123478886899;9739.415635061883;9743.658971935984;9754.170895473857;9645.855312170323;9700.078492543196;9707.951460242724;9720.222382209477;9747.415155090683;9499.24006079512;9616.942136364994;9638.855417168748;9656.517174141265;9658.3722058066;39388;39646.60353396458;39772;39813.80093099545;39874.40188397176;3367.7474189435648;3371.477420999749;3413.6486526634885;3561.8397172127056;3819.1597848473293;27765.583516247272;28371.716282837115;28488.715112848815;28725.138245852657;29470.26324341887;2712.6609173853267;2771.9029833955915;2809.1229327123942;2961.748251398634;2976.4939960206816;484190;484962;486384;486986;488202;52664.21003684948;53499.73250133725;61263.08105378334;62075.6896215516;62281.688591556755;59900.70049649724;59980;60014.39985600176;60054;60573;15978.280977356048;16179.919100404659;16191.190440477929;16203.189840507928;16215.918920405322;10943.179261555415;11144.60993865211;12342;12367.94872435835;12547.498100076078;18744.81255187458;18745.625087498174;18876.716849247274;18902.621947560972;18920.71618925717;7179.641017949081;7833.49082309648;7879.960600196962;8128.593570321578;8153.266206041485;937.7421209167397;952.8475443928938;974.5760594141572;983.4640121133999;1050.9001644843727;26959.191224263304;28606.14181574556;29387.85306073456;30064.085260389795;30252.54621180684;1001.1440218613127;1033.7312298802365;1034.2191645307744;1069.5400977579716;1121.7139629394453;14700.926495367456;15146.394144234107;15324;15545.30046147926;15638;529859;539024;539383;539682.3015884974;541053;56923.43076569264;57260;57284;57316.71341643265;57345.71327143338;1394.1356359057247;1403;1433.7992681024873;1435.5119259451628;1436.9353379098175;25099.623505647796;29062.564061539517;29152.854235728686;29778;30299;53109.93780124453;54264.45735542594;54267.185992210936;54351;54422.72788636031;342915;345412;526081;537128;624104;31032.689673103883;31257.8437107813;31280.530792038593;31326;31366;34056.318873622884;40881.38677919774;41385.58614413817;41410.378844317966;42317;33043.008709738744;35133.297334053685;35532.467012995345;35534.822325888206;36109.458358124124;36249.637503624625;36746.63253367432;37273;37286;37353.62646373502;36304.81847590745;36385.63614363928;37337.43993840149;37408.438873417464;37514;30467.847660761556;32750;32882;32977.505337420436;32991.50512742263;24286.51426971486;24598.754012460362;24760;25106.37234069163;25116.62325065089 05:36:54 INFO - PROCESS | 1675 | |2;jquery.html;304.3456568377988;350.2399792450384;353.4909729988816;355.46325049175744;358.91027243189205;15281.159536225503;15302;18443.26226950922;18728.625427491457;20117.59764804705;17551.2101955412;19650.705239421386;20190.798092019075;21739.13043478259;21793.564128717415;6540.705668244928;6786.524943253974;6800.761973330935;6805.387515123638;6813.965930170342;4635.327877457784;5447.319085114361;6704.698288577026;6809.319068093176;7603.543787372775;7337.853242935138;7443.851122977537;7631.771046868603;7656.502327348732;7678.193789652089;7218.602976836272;7436.776896693108;7510.436717246201;7521.623918804065;7556.886646700304;7102.893456598155;7165.176004759415;7173.0675012248075;7176.246494118094;7176.820579485555;23330.183443579564;23335.53328933429;24137.275881723577;26563.867180664165;27214.72785272142;1925.210663627906;2040.2451093095501;2046.467918341242;2212.811910987568;2786.080593404172;9705.951470242675;9706.417614943126;10155.695329140137;10277.331973421778;13308.334583270798;1737.218251786701;1772.1759381887355;1837.255911355903;1857.349927525369;1866.0762922353385;93847.06152938452;104746.42880356801;104910;105380;106250.46874765655;11234.045106166068;11376;11387.259828111311;11404.429778511041;11465.71335716597;13431.462741490426;13530.45878164882;13545.10368548404;13565.525206617722;13613;8490.405671603052;8504.44721093127;8514.74455766328;8590.656373745105;8596.097409771884;8602;8610.698625548075;8616.655333786579;8693.304535637133;8699.391042627081;3473.7394695397948;4056.2698714231133;4410.514843367227;4476.529964353761;4525.570070843265;1555.6110972256936;1805.3681211576106;1853.9907300463412;1855.1002763659615;1861.6649003179557;824.9711260105987;895.737010814748;912.4525284829182;989.5992123190119;994.5503410383657;15916.602084947968;19429;19702.40892773219;20076.69884951727;20502.58994820095;440.87655456472237;448.1082645535361;454.2958414457644;466.4262956563446;1023.1610079734546;5630.465105815023;5939.584229103922;6096.451319381188;6111.327753947062;6229.190205273286;631093;639814;639882;651660;653593;122445.38777306056;123169.38415308045;123371.3831430855;125258.3737081327;126239;474.0968455092984;478.3351141912744;479.7840971562741;480.4234918098296;480.4402870655717;11414.600488982845;11420.31478111316;11572.59495917639;11586.131040171853;11626.70933226676;50489;52468.21297680614;53642.463575365306;54159.729201353744;54797;70995;73186.90219646816;73620.63189684231;74245.6287718558;76255.85616215873;7855.37157027425;7924.286814186751;7958.283754462126;7984.800379990546;7990.720324788605;6775.7219091315965;7195.9206119082555;7693.9613152225975;8772.078931712078;8817.955910220408;7294.562326260441;7327.74352897646;7782.105057918186;7887.64505597238;8074.757757267292;6796.898046529207;7033.824154395977;7462.8507429850015;8020.518768873886;8095.190480951857;8057.919420805952;8167.55078470666;8185.263326300662;8194.959025204837;8212.055613604512;11232.887671123184;11433.714157146138;11626.76746465083;11658.53365865356;11758.706032348917;11220.943895280798;11262.04272636835;11471.770564588829;11507.482163302839;11581.363025033705 05:36:54 INFO - PROCESS | 1675 | |3;mootools.html;1382.8548002459747;1520.2550750132434;1557.4548907882242;1781.1450503758197;1923.9519012024698;7956.244156805102;8628.654853805849;8777.692780752675;8792.2966162707;8796.824063518727;8456;8752.737417877457;8755.430896991691;8759.737207883774;8783.38516303859;2138.4653836540865;2195.242641288753;2250.570887486446;2295.931122066341;2364.9054037838464;2451.705795304566;2482.7020757509126;2487.2289590227088;2490.6264060390995;2497.4880149569294;2059.392479218634;2435.610302351624;2477.182529765173;2477.8513289202615;2482.416632091457;2075.346265926236;2135.8398120140896;2370.0756704885107;2433.7444568320248;2434.0993832281993;2018.5256464730776;2414.263649586875;2467.6668649732237;2476.8018558515278;2483.627455881632;8703.52130632815;8777.912220877837;8785.077566855452;8786.033536311;8790.033096359459;5373.113436283048;5493.807716729935;5533.253010843525;5545.694986775726;5545.973994810973;3610.801405922673;3673.577538583044;3726.8136593170234;3730.0674831292176;3739.08392443853;1040.5068726377672;1059.639722494348;1061.4268295120626;1087.1140020882956;1100.3068067117665;12207.816882746854;12213;12215.633530994084;12221.694457638541;12239.816402753966;4872.537108974641;4896.069746748107;4969.254611808257;4973.154563724175;4981.028699403581;10335;10349.016843399864;10358.689239322832;10367.170626349875;10390.324628899098;5578.44215578447;5579.414161513064;5593;5593.440655934455;5598.552115830724;4955.157623204064;5054.19132938728;5062.392512898402;5065.746712664426;5066.265391518283;3969.861054863066;3971.384435412516;3973.1259122992897;4013.959860401417;4043.9797801010805;1299.6490947444138;1303.2115570079966;1305.3603734170188;1314.5004898138645;1314.9342532873318;952.7379970508166;975.9512024398751;1316.0984725463088;1319.116192151261;1363.577291039781;7307.707691692272;8041;8191.836163276701;8362.749117526484;8364.874526882102;629.2166253015025;631.5326658272845;641.4195153386192;646.8285904235381;648.7826578096297;6107.694615269219;6111.816645500642;6120.877582448326;6143.293521245025;6144.662043587557;10405.687829365132;10837;10852.403117828595;10936.343819370863;11087.55649774;9200.309976751636;10392.636257730943;10727.463626818628;10861.67414977552;10921.890781092088;8381.371397145238;8459.83080338402;8465.280451161598;8477.279431248413;8542.530160841088;7851.4503984722805;7871.921280787119;7920;7986.920130798618;7991;9432.292578056811;9787.021297870157;9851.704448866545;9889.159421449258;9909.603615855285;8814.823703525766;10105.221064207402;10167;10246.231532634933;10287.331323463952;6693.799186024427;6906.896396554156;7411.370033547209;7496.212897645887;7500.287472690085;8170.387220958571;8223.95888020556;8241.423100382915;8252.257296843312;8262.339012878834;6280.371962803866;7320.377767889576;7382.593957332183;7517.586532740752;7579.658915348712;7216.855662886608;7257.165425976081;7286.48994570375;7309.8903516446235;7316.597587132759;7750.573718445539;7825.765227043198;7828.765137045898;7851.2148785121035;7856.292933635772;7011.824704382431;7025.262347453444;7089.468289878383;7089.574625522485;7092.184398793998;7141.85716285661;7181.856362872609;7186.425085993213;7195.568265904062;7196.0285361475535 05:36:54 INFO - PROCESS | 1675 | |4;prototype.html;318.9585353903992;326.41082845463944;330.0955382252628;330.4283589390353;334.64527600743196;6707.496937729666;6711.597304161747;6728;6733.7306507739695;6756.459483241341;6605.537612367136;6656.367645073722;6680.899786503194;6698.866022679544;6723.327667233274;3797.6012518685484;4976.800927962895;4978.87552811179;4982.5017498250245;4993.775280112403;4695.694779839317;4836.081144582536;4885.5358740919555;4900.093482705711;4900.289458028584;3999.520057593093;4816.662833601649;4816.807327706905;4817.518248175172;4823.614110871139;4639.37368455261;4721.834735784231;4734.644901632391;4832.975835120819;4851.320815085891;4062.8984275393054;4094.344904815215;4109.506859176888;4114.485689288839;4119.464469618961;6318.935316371556;6355.269144048401;6375.521835862329;6401.839954001187;6448;4812.687175333627;5372.167314066326;5465.644733092376;5494.890102197934;5613.382527921925;7068.469864760164;7082.964585177093;7097.254788247212;7106.680199391039;7132.393746531551;4575.885602859922;4577.33628623851;4584.289435137559;4588.908221835544;4592.540745925414;7176.282371762834;7397.112346518345;7413.073365829271;7415.18432972378;7515.887261691079;4604.470485894098;4623.768811559476;4624.838130665478;4637.350770892078;4646.279826626878;4696.483386827446;4702.177119004229;4713.57577817998;4715.552022557851;4717.834875779331;5598.86002849932;5601.80393686219;5602.299712535933;5659.915101273484;5660.094384898396;6859.828504287433;7084.964575177194;7107.502474826712;7116.359527642537;7126.2161162272105;1712.6917154912235;1735.2625134318116;1740.556158179656;1740.7911050674;1740.947771566855;1113.933164010162;1118.9104871610252;1139.9487023083982;1140.3044143072718;1147.4148184426003;6750.223724271772;6758.831029224309;6791.796246112624;6864.24493305736;6884.380405763505;7683.807904802425;7689.50018248812;7698.653560589787;7713.190115037953;7730.033745781778;6653.4677225821815;6780.32196780318;6844.555103918229;6854.383105520527;6856.868616678193;4010.498687664042;4054.351303791379;4058.127502586958;4060.2488539619676;4077.6330130288416;8004.919950800534;8025.558594277254;8040.35677145827;8042.316403105801;8045.396595255379;7065.434765218873;7357.5953322567775;7535.208803075599;7645.082590089225;7952.562609056557;7481.513701609378;7534.924650753423;7535.171131175566;7645.885311720329;7661.655225514865;6141.539384546088;7446.70213191488;7467.365273951775;7474.663640136316;7478.177400486051;7107.324804143598;7317.597532135784;7319.890201647086;7330.633468326669;7336.96331518339;7798.298153166243;7816.88274675869;7819.296263336327;7830.2169783021245;7832.295093441618;4258.339957306623;4659.534046595313;4710.528947105262;4713.410823647044;4718.339432479387;7112.822179445555;7463.701451941862;7464.888026679496;7474.2525747424825;7478.140013898471;7201.7479388223255;7212;7214.350708436266;7214.74748383804;7237.3486386225495;6933.653317334012;7187.5687458752645;7195.568265904062;7218.27817218274;7227.349538541556;7062.3996960258955;7064.964675176797;7078.362947334765;7081.114860642419;7088.964555177191;7215.747448839265;7222.855542889218;7224.638768061681;7232.240614735377;7237.601931893796;6791.388775010273;7061.152661680631;7064.505484616233;7066;7077.256888026683 05:36:54 INFO - PROCESS | 1675 | |5;yui.html;655.0501772429975;675.7938828657259;688.8863337549303;739.2607392607392;759.3166150464581;14979.251037448139;15255.932084754058;15260.694786104272;17439.389621363254;17812.554686132855;15323.463678771246;15341.769873451907;15364.07815531066;15386.769198462032;15396.461123860667;703.4021082080217;716.083413231065;867.77004093915;886.330820230725;890.3055616619048;727.3526561360394;876.5923845411883;886.6098916476761;886.9512176830272;888.3603805260202;927.7263207353847;929.029164523075;929.0894922975455;929.8372784762673;930.8603709443604;735.7719107076797;745.2659130756201;746.1307576673223;746.7237122264743;924.6024209589874;550.8870681510281;671.2917871645412;681.5161235522735;681.6012632609929;682.7678589279619;15127.470538531208;15202.847971520254;15221.162836044014;15223.467178648805;15232.695346093127;12170.208936419192;12287.324197169151;12293.56972505967;12432.316222607753;12637.304948227844;9900.950495247478;9997.200223982063;10037.749056273578;10043.296969212159;10249.9487502562;3473.70473509752;3485.041613556267;3487.319972605343;3487.633798451177;3491.7032052275586;83441.58279208565;84008;84852.3029539412;84927;85012;9266.583003764847;9353.953230233941;9467.100625440573;9519.330260558678;9557.522123893777;17037.14814259282;20798.96005199734;20818.58362832765;20836.37490875246;20853;7377.077865266841;7444.181140074587;7526.134494533089;7557.697692092364;7638.388928885676;4186.581341865789;4195.349720793282;4203.60138331274;4262.50981137167;4406.339049142654;3562.1450851795903;3581.4806853006166;3587.892363229107;3609.9819500902327;3616.439451884962;640.7629177204371;655.9409653131241;658.3515237491067;658.6180015590945;671.3823282580038;561.3544423912533;593.1399470767404;633.4488994574657;637.0571554099959;640.4524131867187;8537.957310213533;8622.655093796178;8665.696700615448;8714.085021072822;8808.691695790616;521.916493361068;542.7530473634487;601.422634271096;614.7295190116334;617.6911544227886;3127.978081321472;3847.5382954045695;3908.785016824045;3980.124372638015;4089;91583;91818;93667;94096;95643;76234.61882690551;76319.61840190875;78819.81770273451;79387;80184;72254.49709526169;72628.27371726216;72644.27355726375;72661.54676906433;73853;8826.293896488138;8922.643094276156;8965.820683586162;8984;9096.226820720312;50306.2454063182;50741;51012.489875102256;51106.722331942;51264.67661691542;61943.690281548304;62105.06842397277;63798.72402552016;64916;69467;10895.346279223271;11015.669529914116;11106.000459958643;11177.217594768288;11532.481038353126;11818.763624727286;11868.76262474763;11873;11882.76234475323;11891.82162267584;11356.318620882774;11423.200375973602;11427.142964277878;11435.714107147387;11448.484818183035;11410.315381077162;11481.138914581274;11492.310461372344;11492.597759078391;11492.770144597229;10773.245872789143;10843;11209;11297.548098076313;11298.378589177673;8441.788955276168;9578.329516934027;9864.605415783288;9945.005499449997;9999.600015999278;9567.995360487326;9970.002999699973;10003.674535469268;10085.394876307446;10104.918000010068 05:36:54 INFO - PROCESS | 1675 | __end_tp_report 05:36:54 INFO - PROCESS | 1675 | __start_cc_report 05:36:54 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1678 05:36:54 INFO - PROCESS | 1675 | __end_cc_report 05:36:54 INFO - PROCESS | 1675 | __startTimestamp1455802614869__endTimestamp 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | ------- Summary: start ------- 05:36:54 INFO - PROCESS | 1675 | Number of tests: 6 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5726.46 Median:5837.04 stddev:635.08 (10.9%) stddev-sans-first:605.71 05:36:54 INFO - PROCESS | 1675 | Values: 3215.4 3476.5 3505.9 3520.4 3558.2 4773.9 4812.6 5709.2 5728.8 5740.6 5606.6 5652.0 5665.0 5668.2 5669.0 5145.8 5156.0 5183.5 5213.7 5249.5 5235.6 5245.5 5263.5 5270.4 5276.1 5255.3 5260.6 5276.0 5278.2 5289.0 5273.8 5321.7 5336.2 5347.2 5370.2 5155.4 5168.5 5183.1 5212.9 5243.9 5592.6 5610.1 5618.2 5628.8 5652.2 6271.7 6286.8 6288.4 6296.7 6301.2 6151.9 6173.5 6262.4 6316.6 6328.4 5822.0 5831.4 5831.8 5881.4 5907.2 6258.2 6271.8 6274.2 6290.9 6302.7 6046.9 6062.3 6070.0 6095.1 6115.8 5667.1 6018.0 6173.5 6234.1 6347.7 6213.0 6227.6 6233.5 6236.5 6244.8 6044.6 6137.1 6242.7 6276.2 6279.2 4862.5 4863.5 4882.4 4884.7 4888.2 4346.3 4439.5 4451.0 4477.3 4479.0 5419.6 5662.7 5677.1 5685.9 5764.7 6349.0 6371.4 6381.7 6383.1 6422.9 5556.1 5683.8 5685.8 5723.0 5737.9 5275.6 5277.8 5281.2 5287.8 5318.7 6275.2 6275.8 6303.7 6320.0 6338.0 6169.8 6203.8 6217.1 6240.7 6323.1 6282.7 6286.0 6294.0 6304.6 6324.1 6207.0 6214.1 6215.4 6239.3 6243.3 6148.3 6225.3 6232.7 6313.8 6322.6 5955.9 6072.7 6087.0 6115.0 6144.3 5903.1 6136.4 6140.1 6146.2 6155.3 5804.7 5833.4 5836.7 5837.0 5838.2 5903.1 5953.1 5967.5 5972.9 5980.9 5818.2 5821.7 5822.7 5823.2 5824.4 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:63720.20 Median:37394.22 stddev:134489.27 (359.7%) stddev-sans-first:134782.60 05:36:54 INFO - PROCESS | 1675 | Values: 429.7 478.9 532.9 541.0 594.3 29030.4 29546.7 29574.7 36629.6 37380.0 40333.8 40652.8 40681.2 40691.0 40718.8 9466.2 9662.2 9671.1 9674.3 9677.3 9483.3 9541.9 9617.8 9636.7 9648.6 9652.4 9739.1 9739.4 9743.7 9754.2 9645.9 9700.1 9708.0 9720.2 9747.4 9499.2 9616.9 9638.9 9656.5 9658.4 39388.0 39646.6 39772.0 39813.8 39874.4 3367.7 3371.5 3413.6 3561.8 3819.2 27765.6 28371.7 28488.7 28725.1 29470.3 2712.7 2771.9 2809.1 2961.7 2976.5 484190.0 484962.0 486384.0 486986.0 488202.0 52664.2 53499.7 61263.1 62075.7 62281.7 59900.7 59980.0 60014.4 60054.0 60573.0 15978.3 16179.9 16191.2 16203.2 16215.9 10943.2 11144.6 12342.0 12367.9 12547.5 18744.8 18745.6 18876.7 18902.6 18920.7 7179.6 7833.5 7880.0 8128.6 8153.3 937.7 952.8 974.6 983.5 1050.9 26959.2 28606.1 29387.9 30064.1 30252.5 1001.1 1033.7 1034.2 1069.5 1121.7 14700.9 15146.4 15324.0 15545.3 15638.0 529859.0 539024.0 539383.0 539682.3 541053.0 56923.4 57260.0 57284.0 57316.7 57345.7 1394.1 1403.0 1433.8 1435.5 1436.9 25099.6 29062.6 29152.9 29778.0 30299.0 53109.9 54264.5 54267.2 54351.0 54422.7 342915.0 345412.0 526081.0 537128.0 624104.0 31032.7 31257.8 31280.5 31326.0 31366.0 34056.3 40881.4 41385.6 41410.4 42317.0 33043.0 35133.3 35532.5 35534.8 36109.5 36249.6 36746.6 37273.0 37286.0 37353.6 36304.8 36385.6 37337.4 37408.4 37514.0 30467.8 32750.0 32882.0 32977.5 32991.5 24286.5 24598.8 24760.0 25106.4 25116.6 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:34963.07 Median:480.10 stddev:106673.80 (22218.9%) stddev-sans-first:106941.31 05:36:54 INFO - PROCESS | 1675 | Values: 304.3 350.2 353.5 355.5 358.9 15281.2 15302.0 18443.3 18728.6 20117.6 17551.2 19650.7 20190.8 21739.1 21793.6 6540.7 6786.5 6800.8 6805.4 6814.0 4635.3 5447.3 6704.7 6809.3 7603.5 7337.9 7443.9 7631.8 7656.5 7678.2 7218.6 7436.8 7510.4 7521.6 7556.9 7102.9 7165.2 7173.1 7176.2 7176.8 23330.2 23335.5 24137.3 26563.9 27214.7 1925.2 2040.2 2046.5 2212.8 2786.1 9706.0 9706.4 10155.7 10277.3 13308.3 1737.2 1772.2 1837.3 1857.3 1866.1 93847.1 104746.4 104910.0 105380.0 106250.5 11234.0 11376.0 11387.3 11404.4 11465.7 13431.5 13530.5 13545.1 13565.5 13613.0 8490.4 8504.4 8514.7 8590.7 8596.1 8602.0 8610.7 8616.7 8693.3 8699.4 3473.7 4056.3 4410.5 4476.5 4525.6 1555.6 1805.4 1854.0 1855.1 1861.7 825.0 895.7 912.5 989.6 994.6 15916.6 19429.0 19702.4 20076.7 20502.6 440.9 448.1 454.3 466.4 1023.2 5630.5 5939.6 6096.5 6111.3 6229.2 631093.0 639814.0 639882.0 651660.0 653593.0 122445.4 123169.4 123371.4 125258.4 126239.0 474.1 478.3 479.8 480.4 480.4 11414.6 11420.3 11572.6 11586.1 11626.7 50489.0 52468.2 53642.5 54159.7 54797.0 70995.0 73186.9 73620.6 74245.6 76255.9 7855.4 7924.3 7958.3 7984.8 7990.7 6775.7 7195.9 7694.0 8772.1 8818.0 7294.6 7327.7 7782.1 7887.6 8074.8 6796.9 7033.8 7462.9 8020.5 8095.2 8057.9 8167.6 8185.3 8195.0 8212.1 11232.9 11433.7 11626.8 11658.5 11758.7 11220.9 11262.0 11471.8 11507.5 11581.4 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:6069.80 Median:5562.21 stddev:3250.11 (58.4%) stddev-sans-first:3240.14 05:36:54 INFO - PROCESS | 1675 | Values: 1382.9 1520.3 1557.5 1781.1 1924.0 7956.2 8628.7 8777.7 8792.3 8796.8 8456.0 8752.7 8755.4 8759.7 8783.4 2138.5 2195.2 2250.6 2295.9 2364.9 2451.7 2482.7 2487.2 2490.6 2497.5 2059.4 2435.6 2477.2 2477.9 2482.4 2075.3 2135.8 2370.1 2433.7 2434.1 2018.5 2414.3 2467.7 2476.8 2483.6 8703.5 8777.9 8785.1 8786.0 8790.0 5373.1 5493.8 5533.3 5545.7 5546.0 3610.8 3673.6 3726.8 3730.1 3739.1 1040.5 1059.6 1061.4 1087.1 1100.3 12207.8 12213.0 12215.6 12221.7 12239.8 4872.5 4896.1 4969.3 4973.2 4981.0 10335.0 10349.0 10358.7 10367.2 10390.3 5578.4 5579.4 5593.0 5593.4 5598.6 4955.2 5054.2 5062.4 5065.7 5066.3 3969.9 3971.4 3973.1 4014.0 4044.0 1299.6 1303.2 1305.4 1314.5 1314.9 952.7 976.0 1316.1 1319.1 1363.6 7307.7 8041.0 8191.8 8362.7 8364.9 629.2 631.5 641.4 646.8 648.8 6107.7 6111.8 6120.9 6143.3 6144.7 10405.7 10837.0 10852.4 10936.3 11087.6 9200.3 10392.6 10727.5 10861.7 10921.9 8381.4 8459.8 8465.3 8477.3 8542.5 7851.5 7871.9 7920.0 7986.9 7991.0 9432.3 9787.0 9851.7 9889.2 9909.6 8814.8 10105.2 10167.0 10246.2 10287.3 6693.8 6906.9 7411.4 7496.2 7500.3 8170.4 8224.0 8241.4 8252.3 8262.3 6280.4 7320.4 7382.6 7517.6 7579.7 7216.9 7257.2 7286.5 7309.9 7316.6 7750.6 7825.8 7828.8 7851.2 7856.3 7011.8 7025.3 7089.5 7089.6 7092.2 7141.9 7181.9 7186.4 7195.6 7196.0 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:5860.73 Median:6741.98 stddev:1900.38 (28.2%) stddev-sans-first:1859.63 05:36:54 INFO - PROCESS | 1675 | Values: 319.0 326.4 330.1 330.4 334.6 6707.5 6711.6 6728.0 6733.7 6756.5 6605.5 6656.4 6680.9 6698.9 6723.3 3797.6 4976.8 4978.9 4982.5 4993.8 4695.7 4836.1 4885.5 4900.1 4900.3 3999.5 4816.7 4816.8 4817.5 4823.6 4639.4 4721.8 4734.6 4833.0 4851.3 4062.9 4094.3 4109.5 4114.5 4119.5 6318.9 6355.3 6375.5 6401.8 6448.0 4812.7 5372.2 5465.6 5494.9 5613.4 7068.5 7083.0 7097.3 7106.7 7132.4 4575.9 4577.3 4584.3 4588.9 4592.5 7176.3 7397.1 7413.1 7415.2 7515.9 4604.5 4623.8 4624.8 4637.4 4646.3 4696.5 4702.2 4713.6 4715.6 4717.8 5598.9 5601.8 5602.3 5659.9 5660.1 6859.8 7085.0 7107.5 7116.4 7126.2 1712.7 1735.3 1740.6 1740.8 1740.9 1113.9 1118.9 1139.9 1140.3 1147.4 6750.2 6758.8 6791.8 6864.2 6884.4 7683.8 7689.5 7698.7 7713.2 7730.0 6653.5 6780.3 6844.6 6854.4 6856.9 4010.5 4054.4 4058.1 4060.2 4077.6 8004.9 8025.6 8040.4 8042.3 8045.4 7065.4 7357.6 7535.2 7645.1 7952.6 7481.5 7534.9 7535.2 7645.9 7661.7 6141.5 7446.7 7467.4 7474.7 7478.2 7107.3 7317.6 7319.9 7330.6 7337.0 7798.3 7816.9 7819.3 7830.2 7832.3 4258.3 4659.5 4710.5 4713.4 4718.3 7112.8 7463.7 7464.9 7474.3 7478.1 7201.7 7212.0 7214.4 7214.7 7237.3 6933.7 7187.6 7195.6 7218.3 7227.3 7062.4 7065.0 7078.4 7081.1 7089.0 7215.7 7222.9 7224.6 7232.2 7237.6 6791.4 7061.2 7064.5 7066.0 7077.3 05:36:54 INFO - PROCESS | 1675 | 05:36:54 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:18502.87 Median:31369.26 stddev:26045.73 (83.0%) stddev-sans-first:26084.32 05:36:54 INFO - PROCESS | 1675 | Values: 655.1 675.8 688.9 739.3 759.3 14979.3 15255.9 15260.7 17439.4 17812.6 15323.5 15341.8 15364.1 15386.8 15396.5 703.4 716.1 867.8 886.3 890.3 727.4 876.6 886.6 887.0 888.4 927.7 929.0 929.1 929.8 930.9 735.8 745.3 746.1 746.7 924.6 550.9 671.3 681.5 681.6 682.8 15127.5 15202.8 15221.2 15223.5 15232.7 12170.2 12287.3 12293.6 12432.3 12637.3 9901.0 9997.2 10037.7 10043.3 10249.9 3473.7 3485.0 3487.3 3487.6 3491.7 83441.6 84008.0 84852.3 84927.0 85012.0 9266.6 9354.0 9467.1 9519.3 9557.5 17037.1 20799.0 20818.6 20836.4 20853.0 7377.1 7444.2 7526.1 7557.7 7638.4 4186.6 4195.3 4203.6 4262.5 4406.3 3562.1 3581.5 3587.9 3610.0 3616.4 640.8 655.9 658.4 658.6 671.4 561.4 593.1 633.4 637.1 640.5 8538.0 8622.7 8665.7 8714.1 8808.7 521.9 542.8 601.4 614.7 617.7 3128.0 3847.5 3908.8 3980.1 4089.0 91583.0 91818.0 93667.0 94096.0 95643.0 76234.6 76319.6 78819.8 79387.0 80184.0 72254.5 72628.3 72644.3 72661.5 73853.0 8826.3 8922.6 8965.8 8984.0 9096.2 50306.2 50741.0 51012.5 51106.7 51264.7 61943.7 62105.1 63798.7 64916.0 69467.0 10895.3 11015.7 11106.0 11177.2 11532.5 11818.8 11868.8 11873.0 11882.8 11891.8 11356.3 11423.2 11427.1 11435.7 11448.5 11410.3 11481.1 11492.3 11492.6 11492.8 10773.2 10843.0 11209.0 11297.5 11298.4 8441.8 9578.3 9864.6 9945.0 9999.6 9568.0 9970.0 10003.7 10085.4 10104.9 05:36:54 INFO - PROCESS | 1675 | -------- Summary: end -------- 05:36:54 INFO - PROCESS | 1675 | 05:36:55 INFO - TEST-INFO | 1675: exit 0 05:36:55 INFO - TEST-OK | dromaeo_css | took 1114142ms 05:36:55 INFO - TEST-START | kraken 05:36:55 INFO - operating with platform_type : mac_ 05:36:55 INFO - Initialising browser for kraken test... 05:36:55 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp7YaHgU/profile http://localhost:49274/getInfo.html) 05:36:57 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 05:36:57 INFO - PROCESS | 1682 | colorDepth:24 05:36:57 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 05:36:57 INFO - PROCESS | 1682 | __metrics 05:36:57 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:36:57 INFO - PROCESS | 1682 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:36:58 INFO - TEST-INFO | 1682: exit 0 05:36:58 INFO - Browser initialized. 05:36:58 INFO - Running cycle 1/1 for kraken test... 05:36:58 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp7YaHgU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:10 INFO - PROCESS | 1685 | RSS: Main: 244477952 05:37:10 INFO - PROCESS | 1685 | 05:37:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:13 INFO - PROCESS | 1685 | RSS: Main: 291139584 05:37:13 INFO - PROCESS | 1685 | 05:37:16 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:37:16 INFO - PROCESS | 1685 | RSS: Main: 300167168 05:37:16 INFO - PROCESS | 1685 | 05:37:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:37:20 INFO - PROCESS | 1685 | RSS: Main: 284680192 05:37:20 INFO - PROCESS | 1685 | 05:37:22 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:37:23 INFO - PROCESS | 1685 | RSS: Main: 287334400 05:37:23 INFO - PROCESS | 1685 | 05:37:25 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:26 INFO - PROCESS | 1685 | RSS: Main: 288145408 05:37:26 INFO - PROCESS | 1685 | 05:37:28 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:29 INFO - PROCESS | 1685 | RSS: Main: 287076352 05:37:29 INFO - PROCESS | 1685 | 05:37:32 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:33 INFO - PROCESS | 1685 | RSS: Main: 288583680 05:37:33 INFO - PROCESS | 1685 | 05:37:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:37:36 INFO - PROCESS | 1685 | RSS: Main: 290799616 05:37:36 INFO - PROCESS | 1685 | 05:37:37 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:38 INFO - PROCESS | 1685 | RSS: Main: 324251648 05:37:38 INFO - PROCESS | 1685 | 05:37:40 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:40 INFO - PROCESS | 1685 | RSS: Main: 321765376 05:37:40 INFO - PROCESS | 1685 | 05:37:42 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:43 INFO - PROCESS | 1685 | RSS: Main: 333217792 05:37:43 INFO - PROCESS | 1685 | 05:37:46 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:46 INFO - PROCESS | 1685 | RSS: Main: 332361728 05:37:46 INFO - PROCESS | 1685 | 05:37:49 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:49 INFO - PROCESS | 1685 | RSS: Main: 333574144 05:37:49 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:37:51 INFO - PROCESS | 1685 | RSS: Main: 393936896 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | __start_tp_report 05:37:51 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 05:37:51 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 05:37:51 INFO - PROCESS | 1685 | |i|pagename|runs| 05:37:51 INFO - PROCESS | 1685 | |0;ai-astar;111;105;111;112;111;111;118;112;111;110 05:37:51 INFO - PROCESS | 1685 | |1;audio-beat-detection;157;123;145;182;147;173;138;120;162;151 05:37:51 INFO - PROCESS | 1685 | |2;audio-dft;189;185;189;184;192;269;199;187;181;185 05:37:51 INFO - PROCESS | 1685 | |3;audio-fft;80;74;82;76;55;57;57;77;78;74 05:37:51 INFO - PROCESS | 1685 | |4;audio-oscillator;89;97;125;79;78;82;113;83;79;113 05:37:51 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;87;92;86;92;85;91;87;132;87;93 05:37:51 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;94;123;148;103;104;149;128;95;94 05:37:51 INFO - PROCESS | 1685 | |7;imaging-desaturate;86;88;87;86;86;84;85;87;85;85 05:37:51 INFO - PROCESS | 1685 | |8;json-parse-financial;55;53;54;54;53;54;53;63;50;52 05:37:51 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;62;70;76;75;71;68;67;67;68;68 05:37:51 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;84;81;83;84;82;84;83;99;106;82 05:37:51 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;132;125;128;137;112;124;126;122;125;122 05:37:51 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;139;125;137;135;137;127;140;131;138;138 05:37:51 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;67;68;66;67;66;67;66;67;66 05:37:51 INFO - PROCESS | 1685 | __end_tp_report 05:37:51 INFO - PROCESS | 1685 | __start_cc_report 05:37:51 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1191 05:37:51 INFO - PROCESS | 1685 | __end_cc_report 05:37:51 INFO - PROCESS | 1685 | __startTimestamp1455802671725__endTimestamp 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | ------- Summary: start ------- 05:37:51 INFO - PROCESS | 1685 | Number of tests: 14 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:3.12 (2.8%) stddev-sans-first:3.31 05:37:51 INFO - PROCESS | 1685 | Values: 111.0 105.0 111.0 112.0 111.0 111.0 118.0 112.0 111.0 110.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:154.00 stddev:19.87 (12.9%) stddev-sans-first:20.90 05:37:51 INFO - PROCESS | 1685 | Values: 157.0 123.0 145.0 182.0 147.0 173.0 138.0 120.0 162.0 151.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:196.00 Median:189.00 stddev:26.13 (13.8%) stddev-sans-first:27.59 05:37:51 INFO - PROCESS | 1685 | Values: 189.0 185.0 189.0 184.0 192.0 269.0 199.0 187.0 181.0 185.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:71.00 Median:76.50 stddev:10.42 (13.6%) stddev-sans-first:10.54 05:37:51 INFO - PROCESS | 1685 | Values: 80.0 74.0 82.0 76.0 55.0 57.0 57.0 77.0 78.0 74.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:80.50 stddev:17.28 (21.5%) stddev-sans-first:18.24 05:37:51 INFO - PROCESS | 1685 | Values: 89.0 97.0 125.0 79.0 78.0 82.0 113.0 83.0 79.0 113.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:89.00 stddev:13.93 (15.7%) stddev-sans-first:14.60 05:37:51 INFO - PROCESS | 1685 | Values: 87.0 92.0 86.0 92.0 85.0 91.0 87.0 132.0 87.0 93.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:121.50 stddev:22.10 (18.2%) stddev-sans-first:22.43 05:37:51 INFO - PROCESS | 1685 | Values: 95.0 94.0 123.0 148.0 103.0 104.0 149.0 128.0 95.0 94.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.27 05:37:51 INFO - PROCESS | 1685 | Values: 86.0 88.0 87.0 86.0 86.0 84.0 85.0 87.0 85.0 85.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:3.41 (6.3%) stddev-sans-first:3.61 05:37:51 INFO - PROCESS | 1685 | Values: 55.0 53.0 54.0 54.0 53.0 54.0 53.0 63.0 50.0 52.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:4.08 (5.9%) stddev-sans-first:3.39 05:37:51 INFO - PROCESS | 1685 | Values: 62.0 70.0 76.0 75.0 71.0 68.0 67.0 67.0 68.0 68.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:83.50 stddev:8.50 (10.2%) stddev-sans-first:8.95 05:37:51 INFO - PROCESS | 1685 | Values: 84.0 81.0 83.0 84.0 82.0 84.0 83.0 99.0 106.0 82.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:125.50 stddev:6.58 (5.2%) stddev-sans-first:6.52 05:37:51 INFO - PROCESS | 1685 | Values: 132.0 125.0 128.0 137.0 112.0 124.0 126.0 122.0 125.0 122.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.70 Median:137.50 stddev:5.23 (3.8%) stddev-sans-first:5.31 05:37:51 INFO - PROCESS | 1685 | Values: 139.0 125.0 137.0 135.0 137.0 127.0 140.0 131.0 138.0 138.0 05:37:51 INFO - PROCESS | 1685 | 05:37:51 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 05:37:51 INFO - PROCESS | 1685 | Values: 67.0 67.0 68.0 66.0 67.0 66.0 67.0 66.0 67.0 66.0 05:37:51 INFO - PROCESS | 1685 | -------- Summary: end -------- 05:37:51 INFO - PROCESS | 1685 | 05:37:52 INFO - TEST-INFO | 1685: exit 0 05:37:52 INFO - TEST-OK | kraken | took 57150ms 05:37:52 INFO - SUITE-END | took 1171s 05:37:52 INFO - Completed test suite (00:19:31) 05:37:52 INFO - Working with test: dromaeo_css 05:37:52 INFO - Generating results file: dromaeo_css 05:37:52 INFO - Working with test: kraken 05:37:52 INFO - Generating results file: kraken 05:37:52 INFO - javascript benchmark 05:37:52 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:37:53 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 05:37:53 INFO - process_Request line: dromaeo_css 7433.69 graph.html#tests=[[72,1,59]] 05:37:53 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:37:53 INFO - process_Request line: kraken 1451.00 graph.html#tests=[[232,1,59]] 05:37:53 INFO - RETURN: dromaeo_css: 7433.69 05:37:53 INFO - RETURN: kraken: 1451.00 05:37:53 INFO - javascript benchmark 05:37:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3215.3730022645577, 3476.5132881396594, 3505.8597656093757, 3520.366334059868, 3558.2171922177113, 4773.856784296477, 4812.614990800736, 5709.229254050707, 5728.828135155947, 5740.6268592541455, 5606.635568688032, 5652.0391533439315, 5665.036943719567, 5668.234788303578, 5669.036263835148, 5145.845624631258, 5155.994581056695, 5183.50756678115, 5213.739313034352, 5249.4750524947585, 5235.633505654606, 5245.4754524547625, 5263.526282634562, 5270.39390470097, 5276.076686579833, 5255.343082114736, 5260.552853007499, 5275.973620131894, 5278.181881808307, 5289.0, 5273.789048438058, 5321.733913304358, 5336.226247194135, 5347.197920311945, 5370.248165256867, 5155.432902380735, 5168.508991645787, 5183.144781111112, 5212.869678258074, 5243.895122097537, 5592.6085174037835, 5610.102383618642, 5618.213450116987, 5628.774849006035, 5652.180433837112, 6271.686415679198, 6286.842828929268, 6288.402601752826, 6296.716647750863, 6301.243850737895, 6151.861905547488, 6173.536984726119, 6262.405071518198, 6316.589421687621, 6328.430441260262, 5822.010258256107, 5831.416858314219, 5831.766729330864, 5881.411858814127, 5907.172995780594, 6258.217722784652, 6271.811845644638, 6274.152989346379, 6290.905636415458, 6302.65335406557, 6046.909296360558, 6062.302835173923, 6070.0110418097265, 6095.105866018796, 6115.816525504242, 5667.121596152603, 6018.0, 6173.475254603409, 6234.127222188898, 6347.746090156435, 6213.0, 6227.595206311576, 6233.501319894398, 6236.532260080512, 6244.781432649836, 6044.576879618385, 6137.079438084323, 6242.719077641426, 6276.246850377984, 6279.152314437584, 4862.513748625109, 4863.513648635179, 4882.389701287339, 4884.706917584886, 4888.242322440027, 4346.326319420495, 4439.511653718088, 4451.0430257494145, 4477.28363461851, 4478.955210447919, 5419.647722897999, 5662.68855212959, 5677.120046392815, 5685.857853553694, 5764.682942438206, 6349.0, 6371.426571608577, 6381.744730210832, 6383.106365108889, 6422.8715425691225, 5556.083246264321, 5683.772649094072, 5685.772569097273, 5722.9984752668115, 5737.85655358611, 5275.630705850554, 5277.815276465382, 5281.234221037928, 5287.814926477555, 5318.7074710891275, 6275.1842260506755, 6275.843103922347, 6303.747850085945, 6320.020396838498, 6338.049292606146, 6169.81490555284, 6203.8138855834395, 6217.129601855653, 6240.719167637558, 6323.1463752394675, 6282.685865706605, 6285.96857015712, 6293.96853015732, 6304.590201636879, 6324.114623952558, 6206.968965155145, 6214.130021797043, 6215.440610345091, 6239.251289845066, 6243.344448832989, 6148.292946311231, 6225.252969643671, 6232.688365581794, 6313.779017734358, 6322.589031712924, 5955.851103722442, 6072.666003369857, 6086.969565152146, 6114.960456722279, 6144.293406258337, 5903.085021821625, 6136.417040381157, 6140.109684095871, 6146.248319228998, 6155.322914479314, 5804.709764511673, 5833.358330583547, 5836.678982655825, 5837.0368889132815, 5838.211841401527, 5903.114532820111, 5953.1367951647635, 5967.4629283362965, 5972.850678733066, 5980.880382392241, 5818.2436283282905, 5821.70891455417, 5822.73797679097, 5823.213866128019, 5824.388439213991], "unit": "score", "name": "dojo.html", "value": 5686.648615431773}, {"lowerIsBetter": false, "replicates": [429.6777416937299, 478.8539495453886, 532.9067413202689, 540.9856519026824, 594.2779522879702, 29030.419391612155, 29546.704532954664, 29574.704252957465, 36629.63370366302, 37380.0, 40333.78998630046, 40652.79673601628, 40681.18637627245, 40691.0, 40718.79640601793, 9466.242700583955, 9662.227021838236, 9671.129598336149, 9674.322797404184, 9677.322587418883, 9483.288753343526, 9541.856872146924, 9617.759556011086, 9636.662716804913, 9648.565814538362, 9652.420854748703, 9739.123478886899, 9739.415635061883, 9743.658971935984, 9754.170895473857, 9645.855312170323, 9700.078492543196, 9707.951460242724, 9720.222382209477, 9747.415155090683, 9499.24006079512, 9616.942136364994, 9638.855417168748, 9656.517174141265, 9658.3722058066, 39388.0, 39646.60353396458, 39772.0, 39813.80093099545, 39874.40188397176, 3367.7474189435648, 3371.477420999749, 3413.6486526634885, 3561.8397172127056, 3819.1597848473293, 27765.583516247272, 28371.716282837115, 28488.715112848815, 28725.138245852657, 29470.26324341887, 2712.6609173853267, 2771.9029833955915, 2809.1229327123942, 2961.748251398634, 2976.4939960206816, 484190.0, 484962.0, 486384.0, 486986.0, 488202.0, 52664.21003684948, 53499.73250133725, 61263.08105378334, 62075.6896215516, 62281.688591556755, 59900.70049649724, 59980.0, 60014.39985600176, 60054.0, 60573.0, 15978.280977356048, 16179.919100404659, 16191.190440477929, 16203.189840507928, 16215.918920405322, 10943.179261555415, 11144.60993865211, 12342.0, 12367.94872435835, 12547.498100076078, 18744.81255187458, 18745.625087498174, 18876.716849247274, 18902.621947560972, 18920.71618925717, 7179.641017949081, 7833.49082309648, 7879.960600196962, 8128.593570321578, 8153.266206041485, 937.7421209167397, 952.8475443928938, 974.5760594141572, 983.4640121133999, 1050.9001644843727, 26959.191224263304, 28606.14181574556, 29387.85306073456, 30064.085260389795, 30252.54621180684, 1001.1440218613127, 1033.7312298802365, 1034.2191645307744, 1069.5400977579716, 1121.7139629394453, 14700.926495367456, 15146.394144234107, 15324.0, 15545.30046147926, 15638.0, 529859.0, 539024.0, 539383.0, 539682.3015884974, 541053.0, 56923.43076569264, 57260.0, 57284.0, 57316.71341643265, 57345.71327143338, 1394.1356359057247, 1403.0, 1433.7992681024873, 1435.5119259451628, 1436.9353379098175, 25099.623505647796, 29062.564061539517, 29152.854235728686, 29778.0, 30299.0, 53109.93780124453, 54264.45735542594, 54267.185992210936, 54351.0, 54422.72788636031, 342915.0, 345412.0, 526081.0, 537128.0, 624104.0, 31032.689673103883, 31257.8437107813, 31280.530792038593, 31326.0, 31366.0, 34056.318873622884, 40881.38677919774, 41385.58614413817, 41410.378844317966, 42317.0, 33043.008709738744, 35133.297334053685, 35532.467012995345, 35534.822325888206, 36109.458358124124, 36249.637503624625, 36746.63253367432, 37273.0, 37286.0, 37353.62646373502, 36304.81847590745, 36385.63614363928, 37337.43993840149, 37408.438873417464, 37514.0, 30467.847660761556, 32750.0, 32882.0, 32977.505337420436, 32991.50512742263, 24286.51426971486, 24598.754012460362, 24760.0, 25106.37234069163, 25116.62325065089], "unit": "score", "name": "ext.html", "value": 19380.88009535828}, {"lowerIsBetter": false, "replicates": [304.3456568377988, 350.2399792450384, 353.4909729988816, 355.46325049175744, 358.91027243189205, 15281.159536225503, 15302.0, 18443.26226950922, 18728.625427491457, 20117.59764804705, 17551.2101955412, 19650.705239421386, 20190.798092019075, 21739.13043478259, 21793.564128717415, 6540.705668244928, 6786.524943253974, 6800.761973330935, 6805.387515123638, 6813.965930170342, 4635.327877457784, 5447.319085114361, 6704.698288577026, 6809.319068093176, 7603.543787372775, 7337.853242935138, 7443.851122977537, 7631.771046868603, 7656.502327348732, 7678.193789652089, 7218.602976836272, 7436.776896693108, 7510.436717246201, 7521.623918804065, 7556.886646700304, 7102.893456598155, 7165.176004759415, 7173.0675012248075, 7176.246494118094, 7176.820579485555, 23330.183443579564, 23335.53328933429, 24137.275881723577, 26563.867180664165, 27214.72785272142, 1925.210663627906, 2040.2451093095501, 2046.467918341242, 2212.811910987568, 2786.080593404172, 9705.951470242675, 9706.417614943126, 10155.695329140137, 10277.331973421778, 13308.334583270798, 1737.218251786701, 1772.1759381887355, 1837.255911355903, 1857.349927525369, 1866.0762922353385, 93847.06152938452, 104746.42880356801, 104910.0, 105380.0, 106250.46874765655, 11234.045106166068, 11376.0, 11387.259828111311, 11404.429778511041, 11465.71335716597, 13431.462741490426, 13530.45878164882, 13545.10368548404, 13565.525206617722, 13613.0, 8490.405671603052, 8504.44721093127, 8514.74455766328, 8590.656373745105, 8596.097409771884, 8602.0, 8610.698625548075, 8616.655333786579, 8693.304535637133, 8699.391042627081, 3473.7394695397948, 4056.2698714231133, 4410.514843367227, 4476.529964353761, 4525.570070843265, 1555.6110972256936, 1805.3681211576106, 1853.9907300463412, 1855.1002763659615, 1861.6649003179557, 824.9711260105987, 895.737010814748, 912.4525284829182, 989.5992123190119, 994.5503410383657, 15916.602084947968, 19429.0, 19702.40892773219, 20076.69884951727, 20502.58994820095, 440.87655456472237, 448.1082645535361, 454.2958414457644, 466.4262956563446, 1023.1610079734546, 5630.465105815023, 5939.584229103922, 6096.451319381188, 6111.327753947062, 6229.190205273286, 631093.0, 639814.0, 639882.0, 651660.0, 653593.0, 122445.38777306056, 123169.38415308045, 123371.3831430855, 125258.3737081327, 126239.0, 474.0968455092984, 478.3351141912744, 479.7840971562741, 480.4234918098296, 480.4402870655717, 11414.600488982845, 11420.31478111316, 11572.59495917639, 11586.131040171853, 11626.70933226676, 50489.0, 52468.21297680614, 53642.463575365306, 54159.729201353744, 54797.0, 70995.0, 73186.90219646816, 73620.63189684231, 74245.6287718558, 76255.85616215873, 7855.37157027425, 7924.286814186751, 7958.283754462126, 7984.800379990546, 7990.720324788605, 6775.7219091315965, 7195.9206119082555, 7693.9613152225975, 8772.078931712078, 8817.955910220408, 7294.562326260441, 7327.74352897646, 7782.105057918186, 7887.64505597238, 8074.757757267292, 6796.898046529207, 7033.824154395977, 7462.8507429850015, 8020.518768873886, 8095.190480951857, 8057.919420805952, 8167.55078470666, 8185.263326300662, 8194.959025204837, 8212.055613604512, 11232.887671123184, 11433.714157146138, 11626.76746465083, 11658.53365865356, 11758.706032348917, 11220.943895280798, 11262.04272636835, 11471.770564588829, 11507.482163302839, 11581.363025033705], "unit": "score", "name": "jquery.html", "value": 8735.831198097034}, {"lowerIsBetter": false, "replicates": [1382.8548002459747, 1520.2550750132434, 1557.4548907882242, 1781.1450503758197, 1923.9519012024698, 7956.244156805102, 8628.654853805849, 8777.692780752675, 8792.2966162707, 8796.824063518727, 8456.0, 8752.737417877457, 8755.430896991691, 8759.737207883774, 8783.38516303859, 2138.4653836540865, 2195.242641288753, 2250.570887486446, 2295.931122066341, 2364.9054037838464, 2451.705795304566, 2482.7020757509126, 2487.2289590227088, 2490.6264060390995, 2497.4880149569294, 2059.392479218634, 2435.610302351624, 2477.182529765173, 2477.8513289202615, 2482.416632091457, 2075.346265926236, 2135.8398120140896, 2370.0756704885107, 2433.7444568320248, 2434.0993832281993, 2018.5256464730776, 2414.263649586875, 2467.6668649732237, 2476.8018558515278, 2483.627455881632, 8703.52130632815, 8777.912220877837, 8785.077566855452, 8786.033536311, 8790.033096359459, 5373.113436283048, 5493.807716729935, 5533.253010843525, 5545.694986775726, 5545.973994810973, 3610.801405922673, 3673.577538583044, 3726.8136593170234, 3730.0674831292176, 3739.08392443853, 1040.5068726377672, 1059.639722494348, 1061.4268295120626, 1087.1140020882956, 1100.3068067117665, 12207.816882746854, 12213.0, 12215.633530994084, 12221.694457638541, 12239.816402753966, 4872.537108974641, 4896.069746748107, 4969.254611808257, 4973.154563724175, 4981.028699403581, 10335.0, 10349.016843399864, 10358.689239322832, 10367.170626349875, 10390.324628899098, 5578.44215578447, 5579.414161513064, 5593.0, 5593.440655934455, 5598.552115830724, 4955.157623204064, 5054.19132938728, 5062.392512898402, 5065.746712664426, 5066.265391518283, 3969.861054863066, 3971.384435412516, 3973.1259122992897, 4013.959860401417, 4043.9797801010805, 1299.6490947444138, 1303.2115570079966, 1305.3603734170188, 1314.5004898138645, 1314.9342532873318, 952.7379970508166, 975.9512024398751, 1316.0984725463088, 1319.116192151261, 1363.577291039781, 7307.707691692272, 8041.0, 8191.836163276701, 8362.749117526484, 8364.874526882102, 629.2166253015025, 631.5326658272845, 641.4195153386192, 646.8285904235381, 648.7826578096297, 6107.694615269219, 6111.816645500642, 6120.877582448326, 6143.293521245025, 6144.662043587557, 10405.687829365132, 10837.0, 10852.403117828595, 10936.343819370863, 11087.55649774, 9200.309976751636, 10392.636257730943, 10727.463626818628, 10861.67414977552, 10921.890781092088, 8381.371397145238, 8459.83080338402, 8465.280451161598, 8477.279431248413, 8542.530160841088, 7851.4503984722805, 7871.921280787119, 7920.0, 7986.920130798618, 7991.0, 9432.292578056811, 9787.021297870157, 9851.704448866545, 9889.159421449258, 9909.603615855285, 8814.823703525766, 10105.221064207402, 10167.0, 10246.231532634933, 10287.331323463952, 6693.799186024427, 6906.896396554156, 7411.370033547209, 7496.212897645887, 7500.287472690085, 8170.387220958571, 8223.95888020556, 8241.423100382915, 8252.257296843312, 8262.339012878834, 6280.371962803866, 7320.377767889576, 7382.593957332183, 7517.586532740752, 7579.658915348712, 7216.855662886608, 7257.165425976081, 7286.48994570375, 7309.8903516446235, 7316.597587132759, 7750.573718445539, 7825.765227043198, 7828.765137045898, 7851.2148785121035, 7856.292933635772, 7011.824704382431, 7025.262347453444, 7089.468289878383, 7089.574625522485, 7092.184398793998, 7141.85716285661, 7181.856362872609, 7186.425085993213, 7195.568265904062, 7196.0285361475535], "unit": "score", "name": "mootools.html", "value": 4860.79655164488}, {"lowerIsBetter": false, "replicates": [318.9585353903992, 326.41082845463944, 330.0955382252628, 330.4283589390353, 334.64527600743196, 6707.496937729666, 6711.597304161747, 6728.0, 6733.7306507739695, 6756.459483241341, 6605.537612367136, 6656.367645073722, 6680.899786503194, 6698.866022679544, 6723.327667233274, 3797.6012518685484, 4976.800927962895, 4978.87552811179, 4982.5017498250245, 4993.775280112403, 4695.694779839317, 4836.081144582536, 4885.5358740919555, 4900.093482705711, 4900.289458028584, 3999.520057593093, 4816.662833601649, 4816.807327706905, 4817.518248175172, 4823.614110871139, 4639.37368455261, 4721.834735784231, 4734.644901632391, 4832.975835120819, 4851.320815085891, 4062.8984275393054, 4094.344904815215, 4109.506859176888, 4114.485689288839, 4119.464469618961, 6318.935316371556, 6355.269144048401, 6375.521835862329, 6401.839954001187, 6448.0, 4812.687175333627, 5372.167314066326, 5465.644733092376, 5494.890102197934, 5613.382527921925, 7068.469864760164, 7082.964585177093, 7097.254788247212, 7106.680199391039, 7132.393746531551, 4575.885602859922, 4577.33628623851, 4584.289435137559, 4588.908221835544, 4592.540745925414, 7176.282371762834, 7397.112346518345, 7413.073365829271, 7415.18432972378, 7515.887261691079, 4604.470485894098, 4623.768811559476, 4624.838130665478, 4637.350770892078, 4646.279826626878, 4696.483386827446, 4702.177119004229, 4713.57577817998, 4715.552022557851, 4717.834875779331, 5598.86002849932, 5601.80393686219, 5602.299712535933, 5659.915101273484, 5660.094384898396, 6859.828504287433, 7084.964575177194, 7107.502474826712, 7116.359527642537, 7126.2161162272105, 1712.6917154912235, 1735.2625134318116, 1740.556158179656, 1740.7911050674, 1740.947771566855, 1113.933164010162, 1118.9104871610252, 1139.9487023083982, 1140.3044143072718, 1147.4148184426003, 6750.223724271772, 6758.831029224309, 6791.796246112624, 6864.24493305736, 6884.380405763505, 7683.807904802425, 7689.50018248812, 7698.653560589787, 7713.190115037953, 7730.033745781778, 6653.4677225821815, 6780.32196780318, 6844.555103918229, 6854.383105520527, 6856.868616678193, 4010.498687664042, 4054.351303791379, 4058.127502586958, 4060.2488539619676, 4077.6330130288416, 8004.919950800534, 8025.558594277254, 8040.35677145827, 8042.316403105801, 8045.396595255379, 7065.434765218873, 7357.5953322567775, 7535.208803075599, 7645.082590089225, 7952.562609056557, 7481.513701609378, 7534.924650753423, 7535.171131175566, 7645.885311720329, 7661.655225514865, 6141.539384546088, 7446.70213191488, 7467.365273951775, 7474.663640136316, 7478.177400486051, 7107.324804143598, 7317.597532135784, 7319.890201647086, 7330.633468326669, 7336.96331518339, 7798.298153166243, 7816.88274675869, 7819.296263336327, 7830.2169783021245, 7832.295093441618, 4258.339957306623, 4659.534046595313, 4710.528947105262, 4713.410823647044, 4718.339432479387, 7112.822179445555, 7463.701451941862, 7464.888026679496, 7474.2525747424825, 7478.140013898471, 7201.7479388223255, 7212.0, 7214.350708436266, 7214.74748383804, 7237.3486386225495, 6933.653317334012, 7187.5687458752645, 7195.568265904062, 7218.27817218274, 7227.349538541556, 7062.3996960258955, 7064.964675176797, 7078.362947334765, 7081.114860642419, 7088.964555177191, 7215.747448839265, 7222.855542889218, 7224.638768061681, 7232.240614735377, 7237.601931893796, 6791.388775010273, 7061.152661680631, 7064.505484616233, 7066.0, 7077.256888026683], "unit": "score", "name": "prototype.html", "value": 5229.886004576552}, {"lowerIsBetter": false, "replicates": [655.0501772429975, 675.7938828657259, 688.8863337549303, 739.2607392607392, 759.3166150464581, 14979.251037448139, 15255.932084754058, 15260.694786104272, 17439.389621363254, 17812.554686132855, 15323.463678771246, 15341.769873451907, 15364.07815531066, 15386.769198462032, 15396.461123860667, 703.4021082080217, 716.083413231065, 867.77004093915, 886.330820230725, 890.3055616619048, 727.3526561360394, 876.5923845411883, 886.6098916476761, 886.9512176830272, 888.3603805260202, 927.7263207353847, 929.029164523075, 929.0894922975455, 929.8372784762673, 930.8603709443604, 735.7719107076797, 745.2659130756201, 746.1307576673223, 746.7237122264743, 924.6024209589874, 550.8870681510281, 671.2917871645412, 681.5161235522735, 681.6012632609929, 682.7678589279619, 15127.470538531208, 15202.847971520254, 15221.162836044014, 15223.467178648805, 15232.695346093127, 12170.208936419192, 12287.324197169151, 12293.56972505967, 12432.316222607753, 12637.304948227844, 9900.950495247478, 9997.200223982063, 10037.749056273578, 10043.296969212159, 10249.9487502562, 3473.70473509752, 3485.041613556267, 3487.319972605343, 3487.633798451177, 3491.7032052275586, 83441.58279208565, 84008.0, 84852.3029539412, 84927.0, 85012.0, 9266.583003764847, 9353.953230233941, 9467.100625440573, 9519.330260558678, 9557.522123893777, 17037.14814259282, 20798.96005199734, 20818.58362832765, 20836.37490875246, 20853.0, 7377.077865266841, 7444.181140074587, 7526.134494533089, 7557.697692092364, 7638.388928885676, 4186.581341865789, 4195.349720793282, 4203.60138331274, 4262.50981137167, 4406.339049142654, 3562.1450851795903, 3581.4806853006166, 3587.892363229107, 3609.9819500902327, 3616.439451884962, 640.7629177204371, 655.9409653131241, 658.3515237491067, 658.6180015590945, 671.3823282580038, 561.3544423912533, 593.1399470767404, 633.4488994574657, 637.0571554099959, 640.4524131867187, 8537.957310213533, 8622.655093796178, 8665.696700615448, 8714.085021072822, 8808.691695790616, 521.916493361068, 542.7530473634487, 601.422634271096, 614.7295190116334, 617.6911544227886, 3127.978081321472, 3847.5382954045695, 3908.785016824045, 3980.124372638015, 4089.0, 91583.0, 91818.0, 93667.0, 94096.0, 95643.0, 76234.61882690551, 76319.61840190875, 78819.81770273451, 79387.0, 80184.0, 72254.49709526169, 72628.27371726216, 72644.27355726375, 72661.54676906433, 73853.0, 8826.293896488138, 8922.643094276156, 8965.820683586162, 8984.0, 9096.226820720312, 50306.2454063182, 50741.0, 51012.489875102256, 51106.722331942, 51264.67661691542, 61943.690281548304, 62105.06842397277, 63798.72402552016, 64916.0, 69467.0, 10895.346279223271, 11015.669529914116, 11106.000459958643, 11177.217594768288, 11532.481038353126, 11818.763624727286, 11868.76262474763, 11873.0, 11882.76234475323, 11891.82162267584, 11356.318620882774, 11423.200375973602, 11427.142964277878, 11435.714107147387, 11448.484818183035, 11410.315381077162, 11481.138914581274, 11492.310461372344, 11492.597759078391, 11492.770144597229, 10773.245872789143, 10843.0, 11209.0, 11297.548098076313, 11298.378589177673, 8441.788955276168, 9578.329516934027, 9864.605415783288, 9945.005499449997, 9999.600015999278, 9567.995360487326, 9970.002999699973, 10003.674535469268, 10085.394876307446, 10104.918000010068], "unit": "score", "name": "yui.html", "value": 6893.8022003430615}], "name": "dromaeo_css", "value": 7433.687903918196}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 105.0, 111.0, 112.0, 111.0, 111.0, 118.0, 112.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 145.0, 182.0, 147.0, 173.0, 138.0, 120.0, 162.0, 151.0], "unit": "score", "name": "audio-beat-detection", "value": 149.8}, {"lowerIsBetter": true, "replicates": [189.0, 185.0, 189.0, 184.0, 192.0, 269.0, 199.0, 187.0, 181.0, 185.0], "unit": "score", "name": "audio-dft", "value": 196.0}, {"lowerIsBetter": true, "replicates": [80.0, 74.0, 82.0, 76.0, 55.0, 57.0, 57.0, 77.0, 78.0, 74.0], "unit": "score", "name": "audio-fft", "value": 71.0}, {"lowerIsBetter": true, "replicates": [89.0, 97.0, 125.0, 79.0, 78.0, 82.0, 113.0, 83.0, 79.0, 113.0], "unit": "score", "name": "audio-oscillator", "value": 93.8}, {"lowerIsBetter": true, "replicates": [87.0, 92.0, 86.0, 92.0, 85.0, 91.0, 87.0, 132.0, 87.0, 93.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.2}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 123.0, 148.0, 103.0, 104.0, 149.0, 128.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 113.3}, {"lowerIsBetter": true, "replicates": [86.0, 88.0, 87.0, 86.0, 86.0, 84.0, 85.0, 87.0, 85.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 85.9}, {"lowerIsBetter": true, "replicates": [55.0, 53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 63.0, 50.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 54.1}, {"lowerIsBetter": true, "replicates": [62.0, 70.0, 76.0, 75.0, 71.0, 68.0, 67.0, 67.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [84.0, 81.0, 83.0, 84.0, 82.0, 84.0, 83.0, 99.0, 106.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.8}, {"lowerIsBetter": true, "replicates": [132.0, 125.0, 128.0, 137.0, 112.0, 124.0, 126.0, 122.0, 125.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.3}, {"lowerIsBetter": true, "replicates": [139.0, 125.0, 137.0, 135.0, 137.0, 127.0, 140.0, 131.0, 138.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.7}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 66.0, 67.0, 66.0, 67.0, 66.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1451.0}]} 05:37:53 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1451.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7433.69"}}} 05:37:53 INFO - Return code: 0 05:37:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:37:53 INFO - # TBPL SUCCESS # 05:37:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:53 INFO - 2016-02-18 05:37:53.444 screenresolution[1689:10106] starting screenresolution argv=screenresolution get 05:37:53 INFO - 2016-02-18 05:37:53.447 screenresolution[1689:10106] Display 0: 1600x1200x32@60 05:37:53 INFO - 2016-02-18 05:37:53.452 screenresolution[1690:10109] starting screenresolution argv=screenresolution list 05:37:53 INFO - Available Modes on Display 0 05:37:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:53 INFO - Intel Iris: 05:37:53 INFO - Chipset Model: Intel Iris 05:37:53 INFO - Type: GPU 05:37:53 INFO - Bus: Built-In 05:37:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:53 INFO - Vendor: Intel (0x8086) 05:37:53 INFO - Device ID: 0x0a2e 05:37:53 INFO - Revision ID: 0x0009 05:37:53 INFO - Displays: 05:37:53 INFO - Display: 05:37:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:53 INFO - Display Serial Number: 1600x1200 60 05:37:53 INFO - Main Display: Yes 05:37:53 INFO - Mirror: Off 05:37:53 INFO - Online: Yes 05:37:53 INFO - Rotation: Supported 05:37:53 INFO - Return code: 0 05:37:53 INFO - Running post-action listener: _resource_record_post_action 05:37:53 INFO - Running post-run listener: _resource_record_post_run 05:37:54 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 38367744; Write bytes: 709923328; Read time: 412; Write time: 1927 05:37:54 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385251840; Write bytes: 380225536; Read time: 29784; Write time: 722 05:37:54 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 37413376; Write bytes: 302144000; Read time: 397; Write time: 1151 05:37:54 INFO - Running post-run listener: _upload_blobber_files 05:37:54 INFO - Blob upload gear active. 05:37:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:54 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:37:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:37:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:37:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:56 INFO - (blobuploader) - INFO - Done attempting. 05:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:57 INFO - (blobuploader) - INFO - Done attempting. 05:37:57 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:57 INFO - Return code: 0 05:37:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75c4f55a8064f024628410fe325a0f2e34dcf4d65737dbc06ee51f43c63fe20891ed3f0cf587fb84a9df34b7da17e549af3d9e0c43123d4af8ebf6863cf6fc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09fc12c2075fc68ade31f2eae332bef98a6ebef43ee1342d8e29e90edc582778520f63a97ef2d1e76c882ecd1bb9dfb076c8c492c13e1e65921731e7c19dff51"} 05:37:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:57 INFO - Contents: 05:37:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75c4f55a8064f024628410fe325a0f2e34dcf4d65737dbc06ee51f43c63fe20891ed3f0cf587fb84a9df34b7da17e549af3d9e0c43123d4af8ebf6863cf6fc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09fc12c2075fc68ade31f2eae332bef98a6ebef43ee1342d8e29e90edc582778520f63a97ef2d1e76c882ecd1bb9dfb076c8c492c13e1e65921731e7c19dff51"} 05:37:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:57 INFO - Copying logs to upload dir... 05:37:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.014653 ========= master_lag: 0.59 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-18 05:37:57.611657) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-18 05:37:57.618455) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75c4f55a8064f024628410fe325a0f2e34dcf4d65737dbc06ee51f43c63fe20891ed3f0cf587fb84a9df34b7da17e549af3d9e0c43123d4af8ebf6863cf6fc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09fc12c2075fc68ade31f2eae332bef98a6ebef43ee1342d8e29e90edc582778520f63a97ef2d1e76c882ecd1bb9dfb076c8c492c13e1e65921731e7c19dff51"} build_url:https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/ABSVx3GcQ_K9xtW9uISS-A/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75c4f55a8064f024628410fe325a0f2e34dcf4d65737dbc06ee51f43c63fe20891ed3f0cf587fb84a9df34b7da17e549af3d9e0c43123d4af8ebf6863cf6fc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09fc12c2075fc68ade31f2eae332bef98a6ebef43ee1342d8e29e90edc582778520f63a97ef2d1e76c882ecd1bb9dfb076c8c492c13e1e65921731e7c19dff51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-18 05:37:57.644998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-02-18 05:37:57.648303) ========= 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.huIjz2sOpj/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.fqhJCKcR6h/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.005167 ========= master_lag: 2.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-02-18 05:38:00.632046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 05:38:00.632390) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 05:38:00.827647) ========= ========= Total master_lag: 6.54 =========