builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0055 starttime: 1455643098.97 results: success (0) buildid: 20160216073850 builduid: 0edee3b4c2774da7b5c2f392177abd35 revision: d4b27a46ac5747d1d3528eed91e6f3dd221662fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:18.966799) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:18.967285) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:18.967638) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005213 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:18.996298) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:18.996621) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.012623) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.012953) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005126 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.060648) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.063996) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.064403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.064702) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-16 09:18:19-- 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% 19.4M=0.001s 2016-02-16 09:18:19 (19.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188535 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.268293) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.268612) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.026138 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:19.320534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-16 09:18:19.323924) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d4b27a46ac5747d1d3528eed91e6f3dd221662fb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d4b27a46ac5747d1d3528eed91e6f3dd221662fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-16 09:18:19,792 truncating revision to first 12 chars 2016-02-16 09:18:19,792 Setting DEBUG logging. 2016-02-16 09:18:19,792 attempt 1/10 2016-02-16 09:18:19,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d4b27a46ac57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-16 09:18:21,383 unpacking tar archive at: fx-team-d4b27a46ac57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.832260 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-16 09:18:21.174988) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:21.175363) ========= echo d4b27a46ac5747d1d3528eed91e6f3dd221662fb in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd4b27a46ac5747d1d3528eed91e6f3dd221662fb'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 d4b27a46ac5747d1d3528eed91e6f3dd221662fb program finished with exit code 0 elapsedTime=0.005314 script_repo_revision: 'd4b27a46ac5747d1d3528eed91e6f3dd221662fb' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:21.225362) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:21.225659) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-16 09:18:21.244155) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-02-16 09:18:21.244431) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:21 INFO - MultiFileLogger online at 20160216 09:18:21 in /builds/slave/test 09:18:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:18:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:18:21 INFO - {'append_to_log': False, 09:18:21 INFO - 'base_work_dir': '/builds/slave/test', 09:18:21 INFO - 'blob_upload_branch': 'Fx-Team', 09:18:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:18:21 INFO - 'branch': 'Fx-Team', 09:18:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:18:21 INFO - 'config_files': ('talos/mac_config.py',), 09:18:21 INFO - 'default_actions': ('clobber', 09:18:21 INFO - 'read-buildbot-config', 09:18:21 INFO - 'download-and-extract', 09:18:21 INFO - 'populate-webroot', 09:18:21 INFO - 'create-virtualenv', 09:18:21 INFO - 'install', 09:18:21 INFO - 'run-tests'), 09:18:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:18:21 INFO - 'download_minidump_stackwalk': True, 09:18:21 INFO - 'download_symbols': 'ondemand', 09:18:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:18:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:18:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:18:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:18:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:18:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:18:21 INFO - 'installer_path': 'installer.exe', 09:18:21 INFO - 'log_level': 'info', 09:18:21 INFO - 'log_name': 'talos', 09:18:21 INFO - 'log_to_console': True, 09:18:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:18:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:18:21 INFO - 'opt_config_files': (), 09:18:21 INFO - 'pip_index': False, 09:18:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:21 INFO - 'cmd': ('bash', 09:18:21 INFO - '-c', 09:18:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:21 INFO - 'enabled': True, 09:18:21 INFO - 'halt_on_failure': False, 09:18:21 INFO - 'name': 'check_screen_resolution'},), 09:18:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:21 INFO - 'cmd': ('bash', 09:18:21 INFO - '-c', 09:18:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:21 INFO - 'enabled': True, 09:18:21 INFO - 'halt_on_failure': False, 09:18:21 INFO - 'name': 'check_screen_resolution'},), 09:18:21 INFO - 'run_cmd_checks_enabled': True, 09:18:21 INFO - 'sps_profile': False, 09:18:21 INFO - 'sps_profile_interval': 0, 09:18:21 INFO - 'suite': 'dromaeojs', 09:18:21 INFO - 'system_bits': '32', 09:18:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:18:21 INFO - 'title': 't-yosemite-r7-0055', 09:18:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:18:21 INFO - 'use_talos_json': True, 09:18:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:18:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:18:21 INFO - 'work_dir': 'build'} 09:18:21 INFO - ##### 09:18:21 INFO - ##### Running clobber step. 09:18:21 INFO - ##### 09:18:21 INFO - Running pre-action listener: _resource_record_pre_action 09:18:21 INFO - Running main action method: clobber 09:18:21 INFO - rmtree: /builds/slave/test/build 09:18:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:18:23 INFO - Running post-action listener: _resource_record_post_action 09:18:23 INFO - ##### 09:18:23 INFO - ##### Running read-buildbot-config step. 09:18:23 INFO - ##### 09:18:23 INFO - Running pre-action listener: _resource_record_pre_action 09:18:23 INFO - Running main action method: read_buildbot_config 09:18:23 INFO - Using buildbot properties: 09:18:23 INFO - { 09:18:23 INFO - "project": "", 09:18:23 INFO - "product": "firefox", 09:18:23 INFO - "script_repo_revision": "production", 09:18:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:18:23 INFO - "repository": "", 09:18:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:18:23 INFO - "buildid": "20160216073850", 09:18:23 INFO - "pgo_build": "False", 09:18:23 INFO - "basedir": "/builds/slave/test", 09:18:23 INFO - "buildnumber": 362, 09:18:23 INFO - "slavename": "t-yosemite-r7-0055", 09:18:23 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:18:23 INFO - "platform": "yosemite_r7", 09:18:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:18:23 INFO - "branch": "fx-team", 09:18:23 INFO - "repo_path": "integration/fx-team", 09:18:23 INFO - "revision": "d4b27a46ac5747d1d3528eed91e6f3dd221662fb", 09:18:23 INFO - "stage_platform": "macosx64", 09:18:23 INFO - "builduid": "0edee3b4c2774da7b5c2f392177abd35", 09:18:23 INFO - "slavebuilddir": "test" 09:18:23 INFO - } 09:18:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 09:18:23 INFO - Running post-action listener: _resource_record_post_action 09:18:23 INFO - ##### 09:18:23 INFO - ##### Running download-and-extract step. 09:18:23 INFO - ##### 09:18:23 INFO - Running pre-action listener: _resource_record_pre_action 09:18:23 INFO - Running main action method: download_and_extract 09:18:23 INFO - mkdir: /builds/slave/test/build/tests 09:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:23 INFO - https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:18:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/test_packages.json 09:18:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/test_packages.json 09:18:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:18:24 INFO - Downloaded 1277 bytes. 09:18:24 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:18:24 INFO - Using the following test package requirements: 09:18:24 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:18:24 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 09:18:24 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 09:18:24 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'jsshell-mac.zip'], 09:18:24 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 09:18:24 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:18:24 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 09:18:24 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 09:18:24 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 09:18:24 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:18:24 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:18:24 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 09:18:24 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:24 INFO - https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:25 INFO - Downloaded 17803602 bytes. 09:18:25 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'] 09:18:25 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 09:18:28 INFO - Return code: 0 09:18:28 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 09:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:28 INFO - https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:29 INFO - Downloaded 17803602 bytes. 09:18:29 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'] 09:18:29 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 09:18:33 INFO - Return code: 0 09:18:33 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')]} 09:18:33 INFO - https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:18:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:18:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:18:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:34 INFO - Downloaded 10707071 bytes. 09:18:34 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'] 09:18:34 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 09:18:34 INFO - Return code: 0 09:18:34 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')]} 09:18:34 INFO - https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:18:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:18:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:18:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 09:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LijX8chtRqCD6UC9zKl8TA/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 09:18:37 INFO - Downloaded 111170098 bytes. 09:18:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:18:37 INFO - mkdir: /builds/slave/test/properties 09:18:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:37 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:37 INFO - Contents: 09:18:37 INFO - build_url:https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:18:38 INFO - Running post-action listener: _resource_record_post_action 09:18:38 INFO - Running post-action listener: set_extra_try_arguments 09:18:38 INFO - ##### 09:18:38 INFO - ##### Running populate-webroot step. 09:18:38 INFO - ##### 09:18:38 INFO - Running pre-action listener: _resource_record_pre_action 09:18:38 INFO - Running main action method: populate_webroot 09:18:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:38 INFO - '--tppagecycles', 09:18:38 INFO - '7'], 09:18:38 INFO - 'tests': ['tsvgm']}}, 09:18:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:18:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:18:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'tests': ['damp', 'tps']}, 09:18:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['damp', 'tps']}, 09:18:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:18:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:38 INFO - 'other': {'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'other_l64': {'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'other_nol64': {'tests': ['a11yr', 09:18:38 INFO - 'ts_paint', 09:18:38 INFO - 'tpaint', 09:18:38 INFO - 'sessionrestore', 09:18:38 INFO - 'sessionrestore_no_auto_restore']}, 09:18:38 INFO - 'svgr': {'tests': ['tsvgx', 09:18:38 INFO - 'tsvgr_opacity', 09:18:38 INFO - 'tart', 09:18:38 INFO - 'tscrollx', 09:18:38 INFO - 'cart']}, 09:18:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['tsvgx', 09:18:38 INFO - 'tsvgr_opacity', 09:18:38 INFO - 'tart', 09:18:38 INFO - 'tscrollx', 09:18:38 INFO - 'cart']}, 09:18:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'tests': ['tp5o']}, 09:18:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'talos_options': ['--e10s'], 09:18:38 INFO - 'tests': ['tp5o']}, 09:18:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'talos_options': ['--xperf_path', 09:18:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:38 INFO - 'tests': ['tp5n']}, 09:18:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:38 INFO - 'talos_options': ['--e10s', 09:18:38 INFO - '--xperf_path', 09:18:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:38 INFO - 'tests': ['tp5n']}}, 09:18:38 INFO - 'talos.zip': {'path': '', 09:18:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:38 INFO - Running post-action listener: _resource_record_post_action 09:18:38 INFO - ##### 09:18:38 INFO - ##### Running create-virtualenv step. 09:18:38 INFO - ##### 09:18:38 INFO - Running pre-action listener: _resource_record_pre_action 09:18:38 INFO - Running main action method: create_virtualenv 09:18:38 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:38 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 09:18:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:38 INFO - Using real prefix '/tools/python27' 09:18:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:39 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:42 INFO - Installing pip.................done. 09:18:42 INFO - Return code: 0 09:18:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:42 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:42 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 09:18:42 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 09:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:42 INFO - 'HOME': '/Users/cltbld', 09:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:42 INFO - 'LOGNAME': 'cltbld', 09:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:42 INFO - 'MOZ_NO_REMOTE': '1', 09:18:42 INFO - 'NO_EM_RESTART': '1', 09:18:42 INFO - 'PAGER': '/bin/cat', 09:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:42 INFO - 'PWD': '/builds/slave/test', 09:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:42 INFO - 'SHELL': '/bin/bash', 09:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:42 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:42 INFO - 'USER': 'cltbld', 09:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:42 INFO - 'XPC_FLAGS': '0x0', 09:18:42 INFO - 'XPC_SERVICE_NAME': '0', 09:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:42 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 09:18:42 INFO - Cleaning up... 09:18:42 INFO - Return code: 0 09:18:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:42 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:42 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 09:18:42 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 09:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:42 INFO - 'HOME': '/Users/cltbld', 09:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:42 INFO - 'LOGNAME': 'cltbld', 09:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:42 INFO - 'MOZ_NO_REMOTE': '1', 09:18:42 INFO - 'NO_EM_RESTART': '1', 09:18:42 INFO - 'PAGER': '/bin/cat', 09:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:42 INFO - 'PWD': '/builds/slave/test', 09:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:42 INFO - 'SHELL': '/bin/bash', 09:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:42 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:42 INFO - 'USER': 'cltbld', 09:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:42 INFO - 'XPC_FLAGS': '0x0', 09:18:42 INFO - 'XPC_SERVICE_NAME': '0', 09:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:43 INFO - Downloading/unpacking psutil>=0.7.1 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:45 INFO - Installing collected packages: psutil 09:18:45 INFO - Running setup.py install for psutil 09:18:45 INFO - building 'psutil._psutil_osx' extension 09:18:45 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 09:18:46 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 09:18:46 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 09:18:46 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 09:18:46 INFO - building 'psutil._psutil_posix' extension 09:18:46 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 09:18:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:18:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:18:46 INFO - ^ 09:18:46 INFO - 1 warning generated. 09:18:46 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 09:18:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:47 INFO - Successfully installed psutil 09:18:47 INFO - Cleaning up... 09:18:47 INFO - Return code: 0 09:18:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:18:47 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')]} 09:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:47 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')]} 09:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:47 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:47 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 09:18:47 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 09:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:47 INFO - 'HOME': '/Users/cltbld', 09:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:47 INFO - 'LOGNAME': 'cltbld', 09:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:47 INFO - 'MOZ_NO_REMOTE': '1', 09:18:47 INFO - 'NO_EM_RESTART': '1', 09:18:47 INFO - 'PAGER': '/bin/cat', 09:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:47 INFO - 'PWD': '/builds/slave/test', 09:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:47 INFO - 'SHELL': '/bin/bash', 09:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:47 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:47 INFO - 'USER': 'cltbld', 09:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:47 INFO - 'XPC_FLAGS': '0x0', 09:18:47 INFO - 'XPC_SERVICE_NAME': '0', 09:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:18:49 INFO - Installing collected packages: mozsystemmonitor 09:18:49 INFO - Running setup.py install for mozsystemmonitor 09:18:49 INFO - Successfully installed mozsystemmonitor 09:18:49 INFO - Cleaning up... 09:18:49 INFO - Return code: 0 09:18:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:49 INFO - 'HOME': '/Users/cltbld', 09:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:49 INFO - 'LOGNAME': 'cltbld', 09:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:49 INFO - 'MOZ_NO_REMOTE': '1', 09:18:49 INFO - 'NO_EM_RESTART': '1', 09:18:49 INFO - 'PAGER': '/bin/cat', 09:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:49 INFO - 'PWD': '/builds/slave/test', 09:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:49 INFO - 'SHELL': '/bin/bash', 09:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:49 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:49 INFO - 'USER': 'cltbld', 09:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:49 INFO - 'XPC_FLAGS': '0x0', 09:18:49 INFO - 'XPC_SERVICE_NAME': '0', 09:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:50 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 09:18:50 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 09:18:50 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 09:18:50 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 09:18:50 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 09:18:50 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 09:18:52 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:53 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 09:18:53 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 09:18:53 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 09:18:53 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 09:18:53 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 09:18:53 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 09:18:53 INFO - Downloading docopt-0.6.1.tar.gz 09:18:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:53 INFO - Running setup.py install for blobuploader 09:18:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:53 INFO - Running setup.py install for requests 09:18:54 INFO - Running setup.py install for docopt 09:18:54 INFO - Successfully installed blobuploader requests docopt 09:18:54 INFO - Cleaning up... 09:18:54 INFO - Return code: 0 09:18:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:54 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:54 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 09:18:54 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 09:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:54 INFO - 'HOME': '/Users/cltbld', 09:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:54 INFO - 'LOGNAME': 'cltbld', 09:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:54 INFO - 'MOZ_NO_REMOTE': '1', 09:18:54 INFO - 'NO_EM_RESTART': '1', 09:18:54 INFO - 'PAGER': '/bin/cat', 09:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:54 INFO - 'PWD': '/builds/slave/test', 09:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:54 INFO - 'SHELL': '/bin/bash', 09:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:54 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:54 INFO - 'USER': 'cltbld', 09:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:54 INFO - 'XPC_FLAGS': '0x0', 09:18:54 INFO - 'XPC_SERVICE_NAME': '0', 09:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-7P6pS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-I3BSm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-0t1COY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-kk1oSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-BLB6Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-baCwCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-apNXPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-1eARog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ZiLn84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-cwzVIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-4OF3ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-YNs1Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-QoWMAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-XrbxIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-URNyUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-_eMr35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ooO6Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:56 INFO - Running setup.py install for manifestparser 09:18:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:56 INFO - Running setup.py install for mozcrash 09:18:56 INFO - Running setup.py install for mozdebug 09:18:57 INFO - Running setup.py install for mozdevice 09:18:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Running setup.py install for mozfile 09:18:57 INFO - Running setup.py install for mozhttpd 09:18:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Running setup.py install for mozinfo 09:18:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Running setup.py install for mozInstall 09:18:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:57 INFO - Running setup.py install for mozleak 09:18:57 INFO - Running setup.py install for mozlog 09:18:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for moznetwork 09:18:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for mozprocess 09:18:58 INFO - Running setup.py install for mozprofile 09:18:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for mozrunner 09:18:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for mozscreenshot 09:18:58 INFO - Running setup.py install for moztest 09:18:59 INFO - Running setup.py install for mozversion 09:18:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:59 INFO - Cleaning up... 09:18:59 INFO - Return code: 0 09:18:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:59 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:18:59 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 09:18:59 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 09:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:59 INFO - 'HOME': '/Users/cltbld', 09:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:59 INFO - 'LOGNAME': 'cltbld', 09:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:59 INFO - 'MOZ_NO_REMOTE': '1', 09:18:59 INFO - 'NO_EM_RESTART': '1', 09:18:59 INFO - 'PAGER': '/bin/cat', 09:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:59 INFO - 'PWD': '/builds/slave/test', 09:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:59 INFO - 'SHELL': '/bin/bash', 09:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:18:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:59 INFO - 'USER': 'cltbld', 09:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:59 INFO - 'XPC_FLAGS': '0x0', 09:18:59 INFO - 'XPC_SERVICE_NAME': '0', 09:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-_93mQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:59 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)) 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-2qkIOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:59 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)) 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-A8GCOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:59 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)) 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-vRfBSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:59 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)) 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-w_5LvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:59 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)) 09:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:59 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-aWmZId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-DRau6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-pnuwLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-5yszPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-O9Vm3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-rdhm_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-UqMLmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Fk9HzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-zWfGZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:00 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)) 09:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:00 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Epz1Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:01 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)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:01 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-DqSxIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:01 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)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:01 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-3zXMrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:01 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)) 09:19:01 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)) 09:19:01 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)) 09:19:01 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)) 09:19:01 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)) 09:19:01 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)) 09:19:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:01 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 09:19:01 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 09:19:01 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 09:19:01 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 09:19:01 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 09:19:01 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 09:19:03 INFO - Downloading blessings-1.6.tar.gz 09:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:03 INFO - Installing collected packages: blessings 09:19:03 INFO - Running setup.py install for blessings 09:19:03 INFO - Successfully installed blessings 09:19:03 INFO - Cleaning up... 09:19:03 INFO - Return code: 0 09:19:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:04 INFO - Reading from file tmpfile_stdout 09:19:04 INFO - Current package versions: 09:19:04 INFO - blessings == 1.6 09:19:04 INFO - blobuploader == 1.2.4 09:19:04 INFO - docopt == 0.6.1 09:19:04 INFO - manifestparser == 1.1 09:19:04 INFO - mozInstall == 1.12 09:19:04 INFO - mozcrash == 0.16 09:19:04 INFO - mozdebug == 0.1 09:19:04 INFO - mozdevice == 0.48 09:19:04 INFO - mozfile == 1.2 09:19:04 INFO - mozhttpd == 0.7 09:19:04 INFO - mozinfo == 0.9 09:19:04 INFO - mozleak == 0.1 09:19:04 INFO - mozlog == 3.1 09:19:04 INFO - moznetwork == 0.27 09:19:04 INFO - mozprocess == 0.22 09:19:04 INFO - mozprofile == 0.28 09:19:04 INFO - mozrunner == 6.11 09:19:04 INFO - mozscreenshot == 0.1 09:19:04 INFO - mozsystemmonitor == 0.0 09:19:04 INFO - moztest == 0.7 09:19:04 INFO - mozversion == 1.4 09:19:04 INFO - psutil == 3.1.1 09:19:04 INFO - requests == 1.2.3 09:19:04 INFO - wsgiref == 0.1.2 09:19:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:04 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')]} 09:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:04 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')]} 09:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:04 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:04 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 09:19:04 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 09:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:04 INFO - 'HOME': '/Users/cltbld', 09:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:04 INFO - 'LOGNAME': 'cltbld', 09:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:04 INFO - 'MOZ_NO_REMOTE': '1', 09:19:04 INFO - 'NO_EM_RESTART': '1', 09:19:04 INFO - 'PAGER': '/bin/cat', 09:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:04 INFO - 'PWD': '/builds/slave/test', 09:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:04 INFO - 'SHELL': '/bin/bash', 09:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:19:04 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:04 INFO - 'USER': 'cltbld', 09:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:04 INFO - 'XPC_FLAGS': '0x0', 09:19:04 INFO - 'XPC_SERVICE_NAME': '0', 09:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 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)) 09:19:04 INFO - Cleaning up... 09:19:04 INFO - Return code: 0 09:19:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:04 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')]} 09:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:04 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')]} 09:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:04 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:04 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 09:19:04 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 09:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:04 INFO - 'HOME': '/Users/cltbld', 09:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:04 INFO - 'LOGNAME': 'cltbld', 09:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:04 INFO - 'MOZ_NO_REMOTE': '1', 09:19:04 INFO - 'NO_EM_RESTART': '1', 09:19:04 INFO - 'PAGER': '/bin/cat', 09:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:04 INFO - 'PWD': '/builds/slave/test', 09:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:04 INFO - 'SHELL': '/bin/bash', 09:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:19:04 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:04 INFO - 'USER': 'cltbld', 09:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:04 INFO - 'XPC_FLAGS': '0x0', 09:19:04 INFO - 'XPC_SERVICE_NAME': '0', 09:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:04 INFO - Downloading/unpacking jsonschema 09:19:04 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 09:19:04 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 09:19:04 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 09:19:04 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 09:19:04 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 09:19:04 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 09:19:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:19:06 INFO - Downloading/unpacking functools32 (from jsonschema) 09:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:19:07 INFO - warning: no files found matching '*.txt' 09:19:07 INFO - no previously-included directories found matching 'build' 09:19:07 INFO - no previously-included directories found matching 'dist' 09:19:07 INFO - no previously-included directories found matching '.git*' 09:19:07 INFO - Installing collected packages: jsonschema, functools32 09:19:07 INFO - Running setup.py install for functools32 09:19:07 INFO - warning: no files found matching '*.txt' 09:19:07 INFO - no previously-included directories found matching 'build' 09:19:07 INFO - no previously-included directories found matching 'dist' 09:19:07 INFO - no previously-included directories found matching '.git*' 09:19:07 INFO - Successfully installed jsonschema functools32 09:19:07 INFO - Cleaning up... 09:19:07 INFO - Return code: 0 09:19:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 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 0x10f849a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8514c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d48d7d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, '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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:07 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 09:19:07 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 09:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:07 INFO - 'HOME': '/Users/cltbld', 09:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:07 INFO - 'LOGNAME': 'cltbld', 09:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:07 INFO - 'MOZ_NO_REMOTE': '1', 09:19:07 INFO - 'NO_EM_RESTART': '1', 09:19:07 INFO - 'PAGER': '/bin/cat', 09:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:07 INFO - 'PWD': '/builds/slave/test', 09:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:07 INFO - 'SHELL': '/bin/bash', 09:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:19:07 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:07 INFO - 'USER': 'cltbld', 09:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:07 INFO - 'XPC_FLAGS': '0x0', 09:19:07 INFO - 'XPC_SERVICE_NAME': '0', 09:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:07 INFO - Downloading/unpacking flake8 09:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:19:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:19:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:19:11 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 09:19:11 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 09:19:11 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 09:19:11 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 09:19:11 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 09:19:11 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 09:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:19:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:19:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:19:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:19:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:19:11 INFO - no previously-included directories found matching 'docs/_build' 09:19:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:19:11 INFO - Running setup.py install for pep8 09:19:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:19:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:19:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:19:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:19:11 INFO - no previously-included directories found matching 'docs/_build' 09:19:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:19:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:19:11 INFO - Cleaning up... 09:19:11 INFO - Return code: 0 09:19:11 INFO - Running post-action listener: _resource_record_post_action 09:19:11 INFO - Running post-action listener: _start_resource_monitoring 09:19:11 INFO - Starting resource monitoring. 09:19:11 INFO - ##### 09:19:11 INFO - ##### Running install step. 09:19:11 INFO - ##### 09:19:11 INFO - Running pre-action listener: _resource_record_pre_action 09:19:11 INFO - Running main action method: install 09:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:12 INFO - Reading from file tmpfile_stdout 09:19:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:19:12 INFO - Reading from file tmpfile_stdout 09:19:12 INFO - Output received: 09:19:12 INFO - Usage: mozinstall [options] installer 09:19:12 INFO - Options: 09:19:12 INFO - -h, --help show this help message and exit 09:19:12 INFO - -d DEST, --destination=DEST 09:19:12 INFO - Directory to install application into. [default: 09:19:12 INFO - "/builds/slave/test"] 09:19:12 INFO - --app=APP Application being installed. [default: firefox] 09:19:12 INFO - mkdir: /builds/slave/test/build/application 09:19:12 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'] 09:19:12 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 09:19:46 INFO - Reading from file tmpfile_stdout 09:19:46 INFO - Output received: 09:19:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:19:46 INFO - Running post-action listener: _resource_record_post_action 09:19:46 INFO - ##### 09:19:46 INFO - ##### Running run-tests step. 09:19:46 INFO - ##### 09:19:46 INFO - Running pre-action listener: _resource_record_pre_action 09:19:46 INFO - Running main action method: run_tests 09:19:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:19:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:19:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:19:46 INFO - 2016-02-16 09:19:46.347 screenresolution[1651:7824] starting screenresolution argv=screenresolution get 09:19:46 INFO - 2016-02-16 09:19:46.349 screenresolution[1651:7824] Display 0: 1600x1200x32@60 09:19:46 INFO - 2016-02-16 09:19:46.356 screenresolution[1652:7827] starting screenresolution argv=screenresolution list 09:19:46 INFO - Available Modes on Display 0 09:19:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:19:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:19:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:19:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:19:46 INFO - Intel Iris: 09:19:46 INFO - Chipset Model: Intel Iris 09:19:46 INFO - Type: GPU 09:19:46 INFO - Bus: Built-In 09:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:19:46 INFO - Vendor: Intel (0x8086) 09:19:46 INFO - Device ID: 0x0a2e 09:19:46 INFO - Revision ID: 0x0009 09:19:46 INFO - Displays: 09:19:46 INFO - Display: 09:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:19:46 INFO - Display Serial Number: 1600x1200 60 09:19:46 INFO - Main Display: Yes 09:19:46 INFO - Mirror: Off 09:19:46 INFO - Online: Yes 09:19:46 INFO - Rotation: Supported 09:19:46 INFO - Return code: 0 09:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:46 INFO - Python 2.7.3 09:19:46 INFO - Return code: 0 09:19:46 INFO - grabbing minidump binary from tooltool 09:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:46 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 0x10f6edd30>, '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 0x10f511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6e4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:46 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 09:19:46 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 09:19:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:46 INFO - Return code: 0 09:19:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:19:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:19:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:19:47 INFO - Return code: 0 09:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 09:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --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 09:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QtOeJ1HuhC/Render', 09:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:47 INFO - 'HOME': '/Users/cltbld', 09:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:47 INFO - 'LOGNAME': 'cltbld', 09:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:47 INFO - 'MOZ_NO_REMOTE': '1', 09:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:47 INFO - 'NO_EM_RESTART': '1', 09:19:47 INFO - 'PAGER': '/bin/cat', 09:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:47 INFO - 'PWD': '/builds/slave/test', 09:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:47 INFO - 'SHELL': '/bin/bash', 09:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BfnKn9GR7a/Listeners', 09:19:47 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:47 INFO - 'USER': 'cltbld', 09:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:47 INFO - 'XPC_FLAGS': '0x0', 09:19:47 INFO - 'XPC_SERVICE_NAME': '0', 09:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 09:19:48 INFO - mozversion application_buildid: 20160216073850 09:19:48 INFO - mozversion application_changeset: d4b27a46ac5747d1d3528eed91e6f3dd221662fb 09:19:48 INFO - mozversion application_display_name: Nightly 09:19:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:48 INFO - mozversion application_name: Firefox 09:19:48 INFO - mozversion application_remotingname: firefox 09:19:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:19:48 INFO - mozversion application_vendor: Mozilla 09:19:48 INFO - mozversion application_version: 47.0a1 09:19:48 INFO - mozversion platform_buildid: 20160216073850 09:19:48 INFO - mozversion platform_changeset: d4b27a46ac5747d1d3528eed91e6f3dd221662fb 09:19:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:19:48 INFO - mozversion platform_version: 47.0a1 09:19:48 INFO - using testdate: 1455643188 09:19:48 INFO - actual date: 1455643188 09:19:48 INFO - starting webserver on 'localhost:49284' 09:19:48 INFO - SUITE-START | Running 2 tests 09:19:48 INFO - TEST-START | dromaeo_css 09:19:48 INFO - operating with platform_type : mac_ 09:19:48 INFO - Initialising browser for dromaeo_css test... 09:19:48 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpHQinzX/profile http://localhost:49284/getInfo.html) 09:19:50 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 09:19:50 INFO - PROCESS | 1670 | colorDepth:24 09:19:50 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 09:19:50 INFO - PROCESS | 1670 | __metrics 09:19:50 INFO - PROCESS | 1670 | 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" 09:19:50 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:51 INFO - TEST-INFO | 1670: exit 0 09:19:51 INFO - Browser initialized. 09:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:51 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpHQinzX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:03 INFO - PROCESS | 1673 | RSS: Main: 243167232 09:20:03 INFO - PROCESS | 1673 | 09:20:54 INFO - PROCESS | 1673 | 1455643254615 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] 09:23:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 09:23:06 INFO - PROCESS | 1673 | RSS: Main: 343998464 09:23:06 INFO - PROCESS | 1673 | 09:26:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 09:26:09 INFO - PROCESS | 1673 | RSS: Main: 374595584 09:26:09 INFO - PROCESS | 1673 | 09:29:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 09:29:12 INFO - PROCESS | 1673 | RSS: Main: 372678656 09:29:12 INFO - PROCESS | 1673 | 09:32:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 09:32:14 INFO - PROCESS | 1673 | RSS: Main: 334045184 09:32:14 INFO - PROCESS | 1673 | 09:35:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 09:35:18 INFO - PROCESS | 1673 | RSS: Main: 334544896 09:35:18 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 09:38:21 INFO - PROCESS | 1673 | RSS: Main: 380882944 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | __start_tp_report 09:38:21 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:38:21 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:38:21 INFO - PROCESS | 1673 | |i|pagename|runs| 09:38:21 INFO - PROCESS | 1673 | |0;dojo.html;3195.424823531764;3486.3898817706895;3499.0727457223857;3505.3865573524627;3537.673372485318;5608.551315894729;5620.1288800235925;5691.7723291068305;5694.629849059808;5719.256496655439;5350.384705758829;5683.744231509581;5693.601447898649;5801.390853960336;5840.182374467578;5131;5138.66598671087;5159.32928719266;5176.456472070435;5300.443453437391;5293.6294459387855;5293.947060529384;5300.337457817773;5347.385050719178;5366.91949620756;5103.9489605103845;5121.897562048757;5195.324607801;5197;5245.344331958505;5178.792848286102;5183.714895680736;5191.273221748958;5202.959408118391;5203.349581302337;5122.359705036871;5157.5616072633875;5162.6128040397125;5185.533302002837;5215.739213039333;5423.430539793344;5514.751836167342;5566.582506312002;5643;5723.942760572382;6195.163652906845;6200;6206.348333425016;6222.0355844844125;6243.906341404882;4533.773311334453;6080.0575910733905;6207.503399728011;6259.624422534663;6262.185915830958;5704.543636509069;5752;5808.07070868659;5817.360090390053;5868.647881127189;6257;6267.6866156691985;6278.560500764994;6283.591566548251;6298.527610429236;5952.613080149777;6007.429294217042;6128.233970753656;6137;6140.232470941132;6133.701927635152;6182.629042257479;6220.813375598739;6226.782062627877;6250.624962502265;6159.353267906895;6196.969015154896;6204.5967012144065;6209.0996805462955;6225.097360882737;5151.639385242997;5270.604704647167;6101.974255744823;6220.129181914535;6227.719752611143;4883.682560633548;4898.804047838046;4904.754762261872;4920.950790492049;4937.975310123426;4364.54172311909;4380.080183161572;4385.166818304576;4406.7576283304725;4446.5331140230455;5478.506934375846;5550.333959924754;5566.415526369754;5642.548596112301;5652.576056795757;6209.455963704371;6226.59527130735;6250.656213908279;6252.781152659635;6260.593061450991;5598.916016259759;5624;5645.096784514458;5657.236273103078;5665.12190610456;4431.8670439886855;5322.760475778599;5333.973330133324;5375.9462405376225;5394.811181608625;6287.339829318039;6288.339724328879;6291.811245662638;6301.842453938688;6308.274548426898;6012.669303188191;6239.06414037898;6242.094896240111;6260.436560709558;6262.499000079892;6243.937560624518;6262.46769024638;6307.36926307384;6307.590006649553;6323.241211054703;6097.756089756537;6136.509079273558;6138.109974053648;6141.84645383869;6146.508279337553;5922.407759224216;6005.309389420097;6233.345498722751;6247.12540244357;6252.218472690914;5812.273465816773;6030.336662967158;6070.1501789750355;6079.088136779518;6079.665618390854;6049.969750151221;6079.604825686316;6081;6081.239845019372;6087.4521293083835;5259.63182577216;5515.641483303572;5828.358880523224;5905.881882362414;5907.852303692442;6058.606190597597;6065.181200537871;6067.484263837447;6095.085737139288;6107.4197951194565;5913;5929.288485381781;5930.911036334544;5935.317438494456;5940.346561878277 09:38:21 INFO - PROCESS | 1673 | |1;ext.html;485.5751217684526;566.9603127781054;567.6765000851515;601.1163589523401;603.2670305578722;29818.956336528197;37727;38036.61963380365;38119;38166.236675266475;40594.98512537181;40964.18071638566;40989.7950510247;41007.794961025225;41010.7949460253;9435.29235307353;9630.75923101921;9636.614535418576;9639.807203855918;9640.855387169198;9514.619415223382;9654.275929305295;9669.371490853073;9672.75818104546;9680.419174849532;9627;9839.409635421862;9844.31089823709;9850.211983041376;9860.802783944317;9016.639334426614;9150.130737579919;9737.318387712863;9796.853047204298;9827.459489728099;9606.27952903528;9763.414195148314;9775.560099795526;9781.266405019582;9784.755381115529;39371.60628393708;39657.40513892294;39685;39733.807985760184;39837.80081099576;3171;3229.0635715642434;3398.5242066110764;3537.5224344713392;3758.3610786166146;28328;29008.41983160346;29703.70296297031;29717.55423668647;29747.70252297471;2718.225305787841;2771.7505424511687;2828.60399544064;2862.055521677841;2872.3967966727014;399551;399970;400207;400432.9999999971;400915;61370.079448808305;61677.76644467086;62075;62137.68931155315;65528.344716553176;69644.65177674181;69651.65174174096;69808;69900;69935.55869957592;13474.461021559027;16310.592235194215;16506.2572184252;16514.174291285628;16540.007599544067;12608.991280697523;12686;12688.49246030167;12689.87310126887;12700.237985720702;16945.11932971398;20405.387838364575;20819.791802082087;20837.37487875366;20840.791592084188;8752.51861147643;8908.242799362128;8914.153155450222;8926.330525210635;8950.955245223862;821.2649678537795;939.9859002114973;947.7441090905417;966.6133546581424;1084.5444913136503;27551.862240688668;28383.716162838522;28409.57385639217;28659.28351791222;28731.85634071858;968.5835090910975;996.3274789517047;1044.1803184500284;1080.7460246841986;1147.984034129801;12928.741425171444;14379.209143497208;14402.08731676222;14556;14626.707465850623;538921;538972;540187;540658;540885;50952.23571646428;58370.12444813331;59107.52231194254;59139;59222.40777592169;1438.9424423022963;1461.7734251191082;1466.2961778346453;1477.0768269831356;1484.695637394353;23767.881160594086;28819.56770648484;29418.705812941596;30233.69766302309;31388;49726.502734973634;50146.749266253435;50223.74888125682;50235.24647130223;50421.74789126177;387873;390889;391020;391636;392010;31560;31934;32268.677313226566;32395.35209295754;32408;43191.78404107959;43462.56537434712;43886.56113438825;44339.33490997702;44847.32729009002;35284;36436.95707302958;36920.81539592392;37043.62956370402;37119.62880371162;35788.82105589456;36523;36778;36827.63172368242;37053.44419833651;36203;36693.816530917175;36882.8155859219;36959.89120326394;36990;30023.3995320088;30085;32391.51412728858;32587;32633.836830815693;24307.28471544417;24568;24591;24667.4993754684;24708.79950408399 09:38:21 INFO - PROCESS | 1673 | |2;jquery.html;334.07294757049203;364.40056107702827;369.593447208071;370.2557858704005;374.24962949286675;15127.319270632821;15446.61383465414;18898.716519252222;19454.610907781855;20286.39140825774;18864.24543018281;18990.525236869085;19170.90414547929;19170.945597992104;19245;5186.922196167051;5826.737796799154;5830.621009634381;5832.416758324176;5861.706914654272;4124.731892426983;4321.286987647034;5784.508316793057;5913.14259432382;6132.6933653317155;6047.123167140762;6267.686615669221;6312.116303717483;6327.303996560375;6329.715162817684;6247.562670613059;6308.905366419507;6316;6317.05244213367;6317.52618553608;5212.895742085175;5418.29562156921;5421.430749771257;5422.91865622016;5523.806666766684;23867.403314917094;23892.163774267992;24265.636015459782;24815.50368992628;24874.25377238686;2342.7657234276435;2384.6423036544484;2525.4696513732138;2599.2679653100763;2638.2085374387607;13916.025878188531;14465.78301325481;14628.853711462962;14637.975341726084;14660.486882959152;1723.2073246306588;1741.2077504735441;1749.9387521436688;1750.161683498341;1759.1643969114568;72784.99999999948;80643.19356806416;84869.57565212196;85660.99999999937;87404;9938.453385063747;10187.643432479828;10721.249512534203;10761.83857242142;10860.294080884718;12800.231986080866;12898.03264755147;12978.35108244584;12981;13014.544490942772;8190.50856948585;8212.137725538852;8215;8263.545504997162;8303.377246706521;8269.751907442786;8275.50346979183;8299.419040667095;8376.455530390627;8381.245687887997;4368.584984426474;4453.242948698729;4458.509563947963;4461.598456138963;4485.529019452975;1496.3416096917322;1653.1238443624898;1799.299661819568;1834.6249371618785;1848.279171123263;917.3578495053489;920.3695468604028;925.0666107834658;925.1026504290827;965.6716716316486;15413.383464661289;17931.37240196587;19854.801451985582;20225.595488090155;20805.479863003242;440.9162259170747;877.7673916412156;926.5413620258015;933.3466573398513;1033.132168978062;5439.972800135974;5532.723363831792;5966.463018328284;6142.262928448526;6145.846353841101;627761;706820;721072;732027;754101;108640.45679771708;114247;114868;114910.85089149169;115266;490.6050629243422;496.15405733224765;507.12014654574284;507.596460813654;507.63957590111437;10656.229380361317;10872.26043832983;10882;11085.168612354268;11129.443527823414;45455.54544454646;45930;47040.7647961758;48645.75677121733;49162;73222;74027.8895816574;74177.25822741704;74673;75056.62471687606;7444.367228785397;7487.042618165685;7742.535447873146;7789.766307010798;7803.297703206738;8512.829743405222;9291.581878815334;11454.599089031844;11741.530338786695;12068.698282543006;8020.478668886503;8831.116888311117;9267.953660231655;9903.55434005457;10189.541470633987;8851.33614978892;9594.66418675343;9725.978772228815;10306.660848177105;10650.573977041142;8496.490210587384;10540.367577945348;10642.787144257227;10706.518206680874;10979.780404392028;7547.05661792276;7660.616969151632;7685.654145563351;7693.49992250502;7709;8696.999845017905;9987.151092156957;10322.535485903003;10332.018458246455;10505.212109092 09:38:21 INFO - PROCESS | 1673 | |3;mootools.html;1345.3878485289194;1516.0524672079948;1558.8830837687174;1786.5265704588287;1943.9514012149702;7924.960375198123;8128.065272493657;8635.740927772162;8859.291256699466;8859.335549833771;8621.353398495123;8651.870221946676;8782.341324400664;8792.340574456925;8795.780105497366;2214.6567282071305;2272.4432514034083;2490.1657944588583;2522.8612426316545;2526.4568117854656;2472.24596498068;2540.504601602688;2544.160427059066;2544.2748816587277;2545.847249165047;2436.4396188876567;2513.6857892763405;2515.5094756522485;2516.006177559863;2520.5085008423366;2487.1046423287603;2506.248125562333;2526.7094284157374;2528.304716203059;2529.165375426114;2491.638712386712;2506.5112303100905;2513.5349960257226;2537.073968001666;2537.6320433537217;8851.557422128932;8904;8905.554722263925;8910.643574257021;8933;5419.078756611346;5528.612997090206;5572.3034620672415;5573.219749235111;5574.637648552831;3491.917505573255;3628.183658676803;3640.9453858192146;3641.3081514512423;3648.7445878788494;911.9452832830051;1062.1343604962008;1071.1056268016216;1092.1153865369076;1098.1379617000696;12172.452239649238;12192.39038048094;12210.938945305306;12220.450079746433;12229.266244025299;4233.872983810491;4942.777575009061;4960.8759781005765;5022.42242142151;5023.397192336943;10335.793284134275;10371.377717336984;10376.221783366127;10378.42918639467;10395.84406233907;5579.469950354791;5587.69267690281;5598.300212473441;5602.1036634138345;5605.607607467438;5062.240663900371;5075.350511083912;5078.111330517145;5083.415407228142;5086.0845047891;3677.7793332400142;3765.8493660253284;3765.9246815063543;3769.4911186989953;4000.6199411055904;1168.2523185161504;1288.8037666586729;1303.88916942061;1325.3042152869627;1331.40086960868;1103.1450625765096;1240.6774238698004;1319.940602672882;1342.4898538555287;1374.1068305601439;8061.556614386265;8140.226678465537;8149.51102933814;8153.062397824327;8167.63245653947;1190.4047976011993;1191.5531675621642;1197.7963746163173;1218.524775337619;1239.0768877186554;6175.135481032659;6180.351063138306;6222.502199824004;6244.65654389013;6247.187865577536;10484.318519296221;10948.178886583539;10948.781024379468;10952.780944381228;10956.506957186946;10366.948165259126;10785.40680262593;10795.352278863293;10806.729831754268;10821.188410869216;8354.58227088643;8364.038135614483;8445.78885527879;8452;8452.239298463168;7888.447808653569;7902.24928631779;7918.604069796372;7933.8413231736195;7974.5215287082965;9391.060893910828;9517.00071492511;9776.071273229032;9780.315377923476;9789.75525611837;9667.27495437859;9993.350432221881;10053.547590358305;10107.19142468586;10135.594576217163;6302.527310451826;7395.556266624019;7398;7483.102027756704;7529.623518824146;7263.16473605521;7473.103227612722;8220.712275070344;8313.08556058821;8314.168583141638;7665.731699390494;7682.961585192262;7715.22847715224;7807.141214466291;7891.921080789349;6506.284308725942;7517.985072015208;7626.809329766578;7635.121960974559;7731.884021739791;7767.115219962657;7790.220977902164;7854.136045035156;7861.017372828396;7883.172266911892;7089.149302083783;7120.430365570845;7147.892781608176;7148.249433809375;7162.2121566628675;7179.282071792779;7200.063991681048;7243.022192004012;7256.2743725629125;7258.129024517092 09:38:21 INFO - PROCESS | 1673 | |4;prototype.html;269.8583243797006;306.82970951122127;332.0221946367946;335.4013086640346;342.95027221052965;6120.571559990803;6340.365963403663;6392.936070639293;6406.743730250796;6443.484521238302;6245.875082498359;6312.810615681526;6325.114483972247;6331.810045698625;6355.3009168991375;4454.420925279709;4525.818967241306;4609.709281401197;4697.201475749113;4707.858764237069;4602.539746025387;4619.422572178478;4640.67515273931;4648.000679853831;4650.511696271894;4574.5882870541645;4576.473705523874;4586.243269860469;4592.5407459253975;4608.262677971526;4329.372241025049;4498.212812757787;4516.887077823064;4598.494165641777;4628.282616194495;3974.364101743707;3975.264576053439;3977.5624681285035;3980.542237642679;3996.840126394941;4728.219843725781;5885.529157667376;5900.409959004108;5917.615355001911;5923.703814809285;5250.396204436501;5371.570274378079;5415.620906536544;5426.050441172819;5426.674399536001;6568.211814582233;6569.507286953498;6570.704318305688;6609.843277426479;6623.847249643837;3792.4880141180865;4367.58507941745;4463.174312752151;4475.820967161309;4478.932816007762;6894.379505844498;6998.125234345707;7006.334398232159;7009.404200642952;7012.088428504313;4429.53489883564;4585.839495617704;4588.472325682523;4589.701669391479;4593.517680643551;4671.902103005791;4675.5324467553655;4680.251159814414;4684.906301874012;4697.412823397075;5395.73021348931;5428.782848686087;5482.424345443751;5487.780488780484;5492.972535137299;6496.675166241669;6711.832204194934;6773.966130169318;6779.084823548857;6784.626845523445;1621.9675606487804;1645.0294326347273;1645.0540938960146;1648.9917550412172;1654.420952666557;1072.8068947589509;1078.2075174746556;1088.1023155896416;1100.7688385438987;1104.2932523185168;6215.751369945152;6228.688565571703;6230.813075607739;6238.220222472191;6260.5617606768;5534.584906132056;6119.969400152971;6363.5545511813725;6933.1333583302085;6996.055532503047;5039.3952725672425;6203.8138855834395;6205.565610407319;6219.191505104398;6221.720022598994;3292.8024318540965;3404.2340473393288;3589.6769290763955;3665.395209790408;3689.6310368963427;7328.560286382834;7332.706691732271;7339.302766237306;7345.19202887682;7347.742829000959;6943.583384996917;7231.927680723231;7234.204237533867;7235.421166306683;7240.094988126484;7296.489245752959;7302.452316076316;7311.305425984523;7329.780106596811;7343.559386436831;7007.474439416962;7007.7196912122945;7010.193827709674;7017.298270172942;7019.3682568569075;6564.770233042012;6890;6905.37851593359;6917.412019978157;6931.480138989697;7315.8536829264185;7334.596597187206;7334.96332518334;7339.70641174347;7348.632568371453;4470.664700147437;4517.277235642281;4520.570545798144;4521.09578084391;4531.660125490535;6769.593824370553;6992.3357281058215;7048;7059.2940705928995;7070.858582828418;6721.361470660279;6731.899021514779;6732.495062870402;6806.400495883365;6829.829254268484;6761.594304341756;6795;6804.96597517029;6807.455403567604;6814.625195614289;5983.534034161658;6398;6449.226092868698;6641.734330626721;6648.407121222824;6536.575122617015;6621.900671489835;6677.2655007948115;6721.126253587003;6732.596044237362;6656.966715166393;6667.49993750461;6689.866202675822;6695.230048544479;6703.229128650268 09:38:21 INFO - PROCESS | 1673 | |5;yui.html;680.5916450129922;695.8747425463416;727.9490435669502;729.9124105107388;747.484235877245;14613.196274204927;14769.926150369232;14791.926040369797;14802.925985370084;14805.555833325005;13275.734485310288;13320.067595268336;13329.933350333258;13331.466741330336;13334.933325333359;676.0737789228764;710.3713213805793;848.4739461533827;877.8814859993915;883.5273128876053;862.1249431826701;872.1714371347214;876.7983363826323;876.8158686675774;877.4603618774439;726.7746998430504;748.7491690283762;933.4212788071405;936.3913456253456;937.8265020971108;897.5691667999364;927.1655510040949;927.452802846324;930.4696323095839;932.1703683721494;692.0657112897599;692.8060143159923;695.3185877839695;695.6626036372355;698.0471656189316;14092.718145637029;14135.293235338295;14156.646083847883;14162.858371416258;14193;12460.252384856938;12551.121421500498;12570;12754.80867786984;12812.743745125139;9993.450360230185;10023.548940297704;10026.749331266703;10051;10066.647667331608;3497.930041399183;3530.135116896351;3531.5585551806025;3535.5757309122814;3538.3630946429635;73461.6326918362;75466;78316;78620;78730.60634696847;9492.620295188253;9497.14525692691;9657.082577155159;9693.854592181124;10264.43545604984;21403.67894481584;21447.463813404476;21454.141834326452;21463.892680536497;21477.248296309866;7011.158660960616;7334.119905611361;7337.889931650922;7376.594287314142;7407.407407407394;4062.8171732272117;4069;4099.0162361033135;4108.239975604463;4141.233871733739;3480.147363895811;3519;3523.5067090607336;3523.71810255179;3531.7638826411066;649.4447247603325;651.3811878715239;653.3792896748108;662.5163630549725;674.3762020131359;565.4486875296635;585.6515373352848;614.7694614519555;619.3094699410125;633.9080833279148;8374.916250837536;8389.119142490074;8501.872471912926;8553.44402613828;8690.695825646071;526.2264471227289;585.7861880413618;604.2054698072021;611.6116266171014;654.4633400611548;3573.8525178965306;3734.2158146788934;3908.472356231929;4065.8170382332864;4081.653059489977;79105;80945.19054809377;81292.55419602954;82531.7620235697;82724;74634.62682686551;75084;75326.62336688282;75567.62216188884;75782;72386.63806680933;72578.63710681413;72684.54630907458;73099.17252068635;73445.26554734384;8766.868496972678;8795.934518269345;8904.910950890408;8973.685920992733;9104.317176211944;47848.760756195996;48847.267290991374;49427.752861235465;49473;50754.74622626863;62486.68756656188;68276;68689.65655171692;69158.65420672865;70430.64784676043;10572.312799667998;10815.405152716361;10910.781784364426;11162.330260184415;11238.988491035847;9917.30578859473;10676.199285053497;11895.881041189823;11969.760604787682;12220;11151.609693660683;11299;11578.015868651259;11739.772018262185;11961.700957476132;11190;11442.542298307975;11479.942600286946;11907.761844762883;11962.820557691815;8135.150670784552;10395.636152734618;10656.730044059846;11021.779564408507;11782.057435405319;7997.680092796223;9274.20362273579;9490.620375185192;9671.516424178622;9968.800623987625;8605.311575073854;9678.56446459925;9710.368826026568;9817.754556136153;9945.850812237966 09:38:21 INFO - PROCESS | 1673 | __end_tp_report 09:38:21 INFO - PROCESS | 1673 | __start_cc_report 09:38:21 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,956 09:38:21 INFO - PROCESS | 1673 | __end_cc_report 09:38:21 INFO - PROCESS | 1673 | __startTimestamp1455644301463__endTimestamp 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:38:21 INFO - PROCESS | 1673 | Number of tests: 6 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5684.30 Median:5905.88 stddev:636.73 (10.8%) stddev-sans-first:608.01 09:38:21 INFO - PROCESS | 1673 | Values: 3195.4 3486.4 3499.1 3505.4 3537.7 5608.6 5620.1 5691.8 5694.6 5719.3 5350.4 5683.7 5693.6 5801.4 5840.2 5131.0 5138.7 5159.3 5176.5 5300.4 5293.6 5293.9 5300.3 5347.4 5366.9 5103.9 5121.9 5195.3 5197.0 5245.3 5178.8 5183.7 5191.3 5203.0 5203.3 5122.4 5157.6 5162.6 5185.5 5215.7 5423.4 5514.8 5566.6 5643.0 5723.9 6195.2 6200.0 6206.3 6222.0 6243.9 4533.8 6080.1 6207.5 6259.6 6262.2 5704.5 5752.0 5808.1 5817.4 5868.6 6257.0 6267.7 6278.6 6283.6 6298.5 5952.6 6007.4 6128.2 6137.0 6140.2 6133.7 6182.6 6220.8 6226.8 6250.6 6159.4 6197.0 6204.6 6209.1 6225.1 5151.6 5270.6 6102.0 6220.1 6227.7 4883.7 4898.8 4904.8 4921.0 4938.0 4364.5 4380.1 4385.2 4406.8 4446.5 5478.5 5550.3 5566.4 5642.5 5652.6 6209.5 6226.6 6250.7 6252.8 6260.6 5598.9 5624.0 5645.1 5657.2 5665.1 4431.9 5322.8 5334.0 5375.9 5394.8 6287.3 6288.3 6291.8 6301.8 6308.3 6012.7 6239.1 6242.1 6260.4 6262.5 6243.9 6262.5 6307.4 6307.6 6323.2 6097.8 6136.5 6138.1 6141.8 6146.5 5922.4 6005.3 6233.3 6247.1 6252.2 5812.3 6030.3 6070.2 6079.1 6079.7 6050.0 6079.6 6081.0 6081.2 6087.5 5259.6 5515.6 5828.4 5905.9 5907.9 6058.6 6065.2 6067.5 6095.1 6107.4 5913.0 5929.3 5930.9 5935.3 5940.3 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:59616.67 Median:390954.50 stddev:119148.81 (30.5%) stddev-sans-first:119400.34 09:38:21 INFO - PROCESS | 1673 | Values: 485.6 567.0 567.7 601.1 603.3 29819.0 37727.0 38036.6 38119.0 38166.2 40595.0 40964.2 40989.8 41007.8 41010.8 9435.3 9630.8 9636.6 9639.8 9640.9 9514.6 9654.3 9669.4 9672.8 9680.4 9627.0 9839.4 9844.3 9850.2 9860.8 9016.6 9150.1 9737.3 9796.9 9827.5 9606.3 9763.4 9775.6 9781.3 9784.8 39371.6 39657.4 39685.0 39733.8 39837.8 3171.0 3229.1 3398.5 3537.5 3758.4 28328.0 29008.4 29703.7 29717.6 29747.7 2718.2 2771.8 2828.6 2862.1 2872.4 399551.0 399970.0 400207.0 400433.0 400915.0 61370.1 61677.8 62075.0 62137.7 65528.3 69644.7 69651.7 69808.0 69900.0 69935.6 13474.5 16310.6 16506.3 16514.2 16540.0 12609.0 12686.0 12688.5 12689.9 12700.2 16945.1 20405.4 20819.8 20837.4 20840.8 8752.5 8908.2 8914.2 8926.3 8951.0 821.3 940.0 947.7 966.6 1084.5 27551.9 28383.7 28409.6 28659.3 28731.9 968.6 996.3 1044.2 1080.7 1148.0 12928.7 14379.2 14402.1 14556.0 14626.7 538921.0 538972.0 540187.0 540658.0 540885.0 50952.2 58370.1 59107.5 59139.0 59222.4 1438.9 1461.8 1466.3 1477.1 1484.7 23767.9 28819.6 29418.7 30233.7 31388.0 49726.5 50146.7 50223.7 50235.2 50421.7 387873.0 390889.0 391020.0 391636.0 392010.0 31560.0 31934.0 32268.7 32395.4 32408.0 43191.8 43462.6 43886.6 44339.3 44847.3 35284.0 36437.0 36920.8 37043.6 37119.6 35788.8 36523.0 36778.0 36827.6 37053.4 36203.0 36693.8 36882.8 36959.9 36990.0 30023.4 30085.0 32391.5 32587.0 32633.8 24307.3 24568.0 24591.0 24667.5 24708.8 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:35673.37 Median:24826.30 stddev:116813.85 (470.5%) stddev-sans-first:117111.40 09:38:21 INFO - PROCESS | 1673 | Values: 334.1 364.4 369.6 370.3 374.2 15127.3 15446.6 18898.7 19454.6 20286.4 18864.2 18990.5 19170.9 19170.9 19245.0 5186.9 5826.7 5830.6 5832.4 5861.7 4124.7 4321.3 5784.5 5913.1 6132.7 6047.1 6267.7 6312.1 6327.3 6329.7 6247.6 6308.9 6316.0 6317.1 6317.5 5212.9 5418.3 5421.4 5422.9 5523.8 23867.4 23892.2 24265.6 24815.5 24874.3 2342.8 2384.6 2525.5 2599.3 2638.2 13916.0 14465.8 14628.9 14638.0 14660.5 1723.2 1741.2 1749.9 1750.2 1759.2 72785.0 80643.2 84869.6 85661.0 87404.0 9938.5 10187.6 10721.2 10761.8 10860.3 12800.2 12898.0 12978.4 12981.0 13014.5 8190.5 8212.1 8215.0 8263.5 8303.4 8269.8 8275.5 8299.4 8376.5 8381.2 4368.6 4453.2 4458.5 4461.6 4485.5 1496.3 1653.1 1799.3 1834.6 1848.3 917.4 920.4 925.1 925.1 965.7 15413.4 17931.4 19854.8 20225.6 20805.5 440.9 877.8 926.5 933.3 1033.1 5440.0 5532.7 5966.5 6142.3 6145.8 627761.0 706820.0 721072.0 732027.0 754101.0 108640.5 114247.0 114868.0 114910.9 115266.0 490.6 496.2 507.1 507.6 507.6 10656.2 10872.3 10882.0 11085.2 11129.4 45455.5 45930.0 47040.8 48645.8 49162.0 73222.0 74027.9 74177.3 74673.0 75056.6 7444.4 7487.0 7742.5 7789.8 7803.3 8512.8 9291.6 11454.6 11741.5 12068.7 8020.5 8831.1 9268.0 9903.6 10189.5 8851.3 9594.7 9726.0 10306.7 10650.6 8496.5 10540.4 10642.8 10706.5 10979.8 7547.1 7660.6 7685.7 7693.5 7709.0 8697.0 9987.2 10322.5 10332.0 10505.2 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:6123.23 Median:5084.75 stddev:3221.66 (63.4%) stddev-sans-first:3210.67 09:38:21 INFO - PROCESS | 1673 | Values: 1345.4 1516.1 1558.9 1786.5 1944.0 7925.0 8128.1 8635.7 8859.3 8859.3 8621.4 8651.9 8782.3 8792.3 8795.8 2214.7 2272.4 2490.2 2522.9 2526.5 2472.2 2540.5 2544.2 2544.3 2545.8 2436.4 2513.7 2515.5 2516.0 2520.5 2487.1 2506.2 2526.7 2528.3 2529.2 2491.6 2506.5 2513.5 2537.1 2537.6 8851.6 8904.0 8905.6 8910.6 8933.0 5419.1 5528.6 5572.3 5573.2 5574.6 3491.9 3628.2 3640.9 3641.3 3648.7 911.9 1062.1 1071.1 1092.1 1098.1 12172.5 12192.4 12210.9 12220.5 12229.3 4233.9 4942.8 4960.9 5022.4 5023.4 10335.8 10371.4 10376.2 10378.4 10395.8 5579.5 5587.7 5598.3 5602.1 5605.6 5062.2 5075.4 5078.1 5083.4 5086.1 3677.8 3765.8 3765.9 3769.5 4000.6 1168.3 1288.8 1303.9 1325.3 1331.4 1103.1 1240.7 1319.9 1342.5 1374.1 8061.6 8140.2 8149.5 8153.1 8167.6 1190.4 1191.6 1197.8 1218.5 1239.1 6175.1 6180.4 6222.5 6244.7 6247.2 10484.3 10948.2 10948.8 10952.8 10956.5 10366.9 10785.4 10795.4 10806.7 10821.2 8354.6 8364.0 8445.8 8452.0 8452.2 7888.4 7902.2 7918.6 7933.8 7974.5 9391.1 9517.0 9776.1 9780.3 9789.8 9667.3 9993.4 10053.5 10107.2 10135.6 6302.5 7395.6 7398.0 7483.1 7529.6 7263.2 7473.1 8220.7 8313.1 8314.2 7665.7 7683.0 7715.2 7807.1 7891.9 6506.3 7518.0 7626.8 7635.1 7731.9 7767.1 7790.2 7854.1 7861.0 7883.2 7089.1 7120.4 7147.9 7148.2 7162.2 7179.3 7200.1 7243.0 7256.3 7258.1 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5509.44 Median:6229.75 stddev:1749.34 (28.1%) stddev-sans-first:1709.47 09:38:21 INFO - PROCESS | 1673 | Values: 269.9 306.8 332.0 335.4 343.0 6120.6 6340.4 6392.9 6406.7 6443.5 6245.9 6312.8 6325.1 6331.8 6355.3 4454.4 4525.8 4609.7 4697.2 4707.9 4602.5 4619.4 4640.7 4648.0 4650.5 4574.6 4576.5 4586.2 4592.5 4608.3 4329.4 4498.2 4516.9 4598.5 4628.3 3974.4 3975.3 3977.6 3980.5 3996.8 4728.2 5885.5 5900.4 5917.6 5923.7 5250.4 5371.6 5415.6 5426.1 5426.7 6568.2 6569.5 6570.7 6609.8 6623.8 3792.5 4367.6 4463.2 4475.8 4478.9 6894.4 6998.1 7006.3 7009.4 7012.1 4429.5 4585.8 4588.5 4589.7 4593.5 4671.9 4675.5 4680.3 4684.9 4697.4 5395.7 5428.8 5482.4 5487.8 5493.0 6496.7 6711.8 6774.0 6779.1 6784.6 1622.0 1645.0 1645.1 1649.0 1654.4 1072.8 1078.2 1088.1 1100.8 1104.3 6215.8 6228.7 6230.8 6238.2 6260.6 5534.6 6120.0 6363.6 6933.1 6996.1 5039.4 6203.8 6205.6 6219.2 6221.7 3292.8 3404.2 3589.7 3665.4 3689.6 7328.6 7332.7 7339.3 7345.2 7347.7 6943.6 7231.9 7234.2 7235.4 7240.1 7296.5 7302.5 7311.3 7329.8 7343.6 7007.5 7007.7 7010.2 7017.3 7019.4 6564.8 6890.0 6905.4 6917.4 6931.5 7315.9 7334.6 7335.0 7339.7 7348.6 4470.7 4517.3 4520.6 4521.1 4531.7 6769.6 6992.3 7048.0 7059.3 7070.9 6721.4 6731.9 6732.5 6806.4 6829.8 6761.6 6795.0 6805.0 6807.5 6814.6 5983.5 6398.0 6449.2 6641.7 6648.4 6536.6 6621.9 6677.3 6721.1 6732.6 6657.0 6667.5 6689.9 6695.2 6703.2 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:17793.80 Median:653.92 stddev:24577.71 (3758.5%) stddev-sans-first:24613.07 09:38:21 INFO - PROCESS | 1673 | Values: 680.6 695.9 727.9 729.9 747.5 14613.2 14769.9 14791.9 14802.9 14805.6 13275.7 13320.1 13329.9 13331.5 13334.9 676.1 710.4 848.5 877.9 883.5 862.1 872.2 876.8 876.8 877.5 726.8 748.7 933.4 936.4 937.8 897.6 927.2 927.5 930.5 932.2 692.1 692.8 695.3 695.7 698.0 14092.7 14135.3 14156.6 14162.9 14193.0 12460.3 12551.1 12570.0 12754.8 12812.7 9993.5 10023.5 10026.7 10051.0 10066.6 3497.9 3530.1 3531.6 3535.6 3538.4 73461.6 75466.0 78316.0 78620.0 78730.6 9492.6 9497.1 9657.1 9693.9 10264.4 21403.7 21447.5 21454.1 21463.9 21477.2 7011.2 7334.1 7337.9 7376.6 7407.4 4062.8 4069.0 4099.0 4108.2 4141.2 3480.1 3519.0 3523.5 3523.7 3531.8 649.4 651.4 653.4 662.5 674.4 565.4 585.7 614.8 619.3 633.9 8374.9 8389.1 8501.9 8553.4 8690.7 526.2 585.8 604.2 611.6 654.5 3573.9 3734.2 3908.5 4065.8 4081.7 79105.0 80945.2 81292.6 82531.8 82724.0 74634.6 75084.0 75326.6 75567.6 75782.0 72386.6 72578.6 72684.5 73099.2 73445.3 8766.9 8795.9 8904.9 8973.7 9104.3 47848.8 48847.3 49427.8 49473.0 50754.7 62486.7 68276.0 68689.7 69158.7 70430.6 10572.3 10815.4 10910.8 11162.3 11239.0 9917.3 10676.2 11895.9 11969.8 12220.0 11151.6 11299.0 11578.0 11739.8 11961.7 11190.0 11442.5 11479.9 11907.8 11962.8 8135.2 10395.6 10656.7 11021.8 11782.1 7997.7 9274.2 9490.6 9671.5 9968.8 8605.3 9678.6 9710.4 9817.8 9945.9 09:38:21 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:38:21 INFO - PROCESS | 1673 | 09:38:21 INFO - TEST-INFO | 1673: exit 0 09:38:22 INFO - TEST-OK | dromaeo_css | took 1113437ms 09:38:22 INFO - TEST-START | kraken 09:38:22 INFO - operating with platform_type : mac_ 09:38:22 INFO - Initialising browser for kraken test... 09:38:22 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmp3C7hbk/profile http://localhost:49284/getInfo.html) 09:38:24 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 09:38:24 INFO - PROCESS | 1683 | colorDepth:24 09:38:24 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 09:38:24 INFO - PROCESS | 1683 | __metrics 09:38:24 INFO - PROCESS | 1683 | 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" 09:38:24 INFO - PROCESS | 1683 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:25 INFO - TEST-INFO | 1683: exit 0 09:38:25 INFO - Browser initialized. 09:38:25 INFO - Running cycle 1/1 for kraken test... 09:38:25 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmp3C7hbk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:37 INFO - PROCESS | 1685 | RSS: Main: 244404224 09:38:37 INFO - PROCESS | 1685 | 09:38:39 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:40 INFO - PROCESS | 1685 | RSS: Main: 290152448 09:38:40 INFO - PROCESS | 1685 | 09:38:42 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 09:38:43 INFO - PROCESS | 1685 | RSS: Main: 299790336 09:38:43 INFO - PROCESS | 1685 | 09:38:46 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 09:38:47 INFO - PROCESS | 1685 | RSS: Main: 286863360 09:38:47 INFO - PROCESS | 1685 | 09:38:49 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 09:38:49 INFO - PROCESS | 1685 | RSS: Main: 290230272 09:38:49 INFO - PROCESS | 1685 | 09:38:51 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:52 INFO - PROCESS | 1685 | RSS: Main: 289046528 09:38:52 INFO - PROCESS | 1685 | 09:38:55 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:55 INFO - PROCESS | 1685 | RSS: Main: 289234944 09:38:55 INFO - PROCESS | 1685 | 09:38:58 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:59 INFO - PROCESS | 1685 | RSS: Main: 289411072 09:38:59 INFO - PROCESS | 1685 | 09:39:01 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 09:39:02 INFO - PROCESS | 1685 | RSS: Main: 290390016 09:39:02 INFO - PROCESS | 1685 | 09:39:04 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:04 INFO - PROCESS | 1685 | RSS: Main: 323702784 09:39:04 INFO - PROCESS | 1685 | 09:39:06 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:39:07 INFO - PROCESS | 1685 | RSS: Main: 323223552 09:39:07 INFO - PROCESS | 1685 | 09:39:09 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:39:09 INFO - PROCESS | 1685 | RSS: Main: 331386880 09:39:09 INFO - PROCESS | 1685 | 09:39:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:39:12 INFO - PROCESS | 1685 | RSS: Main: 334061568 09:39:12 INFO - PROCESS | 1685 | 09:39:15 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:39:16 INFO - PROCESS | 1685 | RSS: Main: 333844480 09:39:16 INFO - PROCESS | 1685 | 09:39:17 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 09:39:17 INFO - PROCESS | 1685 | RSS: Main: 394555392 09:39:17 INFO - PROCESS | 1685 | 09:39:17 INFO - PROCESS | 1685 | __start_tp_report 09:39:17 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 09:39:17 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 09:39:17 INFO - PROCESS | 1685 | |i|pagename|runs| 09:39:17 INFO - PROCESS | 1685 | |0;ai-astar;111;108;110;111;111;112;117;111;109;112 09:39:17 INFO - PROCESS | 1685 | |1;audio-beat-detection;157;121;144;172;146;148;156;145;173;117 09:39:17 INFO - PROCESS | 1685 | |2;audio-dft;184;183;187;184;188;223;185;181;180;180 09:39:17 INFO - PROCESS | 1685 | |3;audio-fft;73;77;89;78;57;59;58;81;81;65 09:39:17 INFO - PROCESS | 1685 | |4;audio-oscillator;90;94;124;79;80;84;108;77;79;111 09:39:17 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;84;90;87;93;83;89;85;90;84;89 09:39:17 INFO - PROCESS | 1685 | |6;imaging-darkroom;93;93;93;94;93;93;93;93;93;92 09:39:17 INFO - PROCESS | 1685 | |7;imaging-desaturate;85;88;86;85;87;86;91;86;85;91 09:39:17 INFO - PROCESS | 1685 | |8;json-parse-financial;55;56;57;55;54;54;54;64;55;52 09:39:17 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;66;61;68;77;68;67;66;66;66;66 09:39:17 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;83;84;88;82;83;82;83;86;106;106 09:39:17 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;127;124;126;123;121;127;125;126;122;121 09:39:17 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;162;176;175;136;139;127;140;135;136;136 09:39:17 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;66;67;68;66;67 09:39:17 INFO - PROCESS | 1685 | __end_tp_report 09:39:17 INFO - PROCESS | 1685 | __start_cc_report 09:39:17 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1159 09:39:17 INFO - PROCESS | 1685 | __end_cc_report 09:39:17 INFO - PROCESS | 1685 | __startTimestamp1455644357994__endTimestamp 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | ------- Summary: start ------- 09:39:18 INFO - PROCESS | 1685 | Number of tests: 14 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:2.39 (2.2%) stddev-sans-first:2.54 09:39:18 INFO - PROCESS | 1685 | Values: 111.0 108.0 110.0 111.0 111.0 112.0 117.0 111.0 109.0 112.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:152.00 stddev:18.45 (12.1%) stddev-sans-first:19.28 09:39:18 INFO - PROCESS | 1685 | Values: 157.0 121.0 144.0 172.0 146.0 148.0 156.0 145.0 173.0 117.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:187.50 Median:184.50 stddev:12.76 (6.9%) stddev-sans-first:13.48 09:39:18 INFO - PROCESS | 1685 | Values: 184.0 183.0 187.0 184.0 188.0 223.0 185.0 181.0 180.0 180.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:71.80 Median:77.50 stddev:11.31 (14.6%) stddev-sans-first:11.99 09:39:18 INFO - PROCESS | 1685 | Values: 73.0 77.0 89.0 78.0 57.0 59.0 58.0 81.0 81.0 65.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:79.50 stddev:16.39 (20.6%) stddev-sans-first:17.35 09:39:18 INFO - PROCESS | 1685 | Values: 90.0 94.0 124.0 79.0 80.0 84.0 108.0 77.0 79.0 111.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:89.00 stddev:3.31 (3.7%) stddev-sans-first:3.27 09:39:18 INFO - PROCESS | 1685 | Values: 84.0 90.0 87.0 93.0 83.0 89.0 85.0 90.0 84.0 89.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:39:18 INFO - PROCESS | 1685 | Values: 93.0 93.0 93.0 94.0 93.0 93.0 93.0 93.0 93.0 92.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:86.50 stddev:2.31 (2.7%) stddev-sans-first:2.33 09:39:18 INFO - PROCESS | 1685 | Values: 85.0 88.0 86.0 85.0 87.0 86.0 91.0 86.0 85.0 91.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.00 stddev:3.24 (5.9%) stddev-sans-first:3.43 09:39:18 INFO - PROCESS | 1685 | Values: 55.0 56.0 57.0 55.0 54.0 54.0 54.0 64.0 55.0 52.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:66.50 stddev:3.98 (6.0%) stddev-sans-first:4.21 09:39:18 INFO - PROCESS | 1685 | Values: 66.0 61.0 68.0 77.0 68.0 67.0 66.0 66.0 66.0 66.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:83.00 stddev:9.51 (11.5%) stddev-sans-first:9.89 09:39:18 INFO - PROCESS | 1685 | Values: 83.0 84.0 88.0 82.0 83.0 82.0 83.0 86.0 106.0 106.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:125.50 stddev:2.35 (1.9%) stddev-sans-first:2.26 09:39:18 INFO - PROCESS | 1685 | Values: 127.0 124.0 126.0 123.0 121.0 127.0 125.0 126.0 122.0 121.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.20 Median:139.50 stddev:17.84 (12.8%) stddev-sans-first:17.98 09:39:18 INFO - PROCESS | 1685 | Values: 162.0 176.0 175.0 136.0 139.0 127.0 140.0 135.0 136.0 136.0 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 09:39:18 INFO - PROCESS | 1685 | Values: 67.0 67.0 67.0 67.0 66.0 66.0 67.0 68.0 66.0 67.0 09:39:18 INFO - PROCESS | 1685 | -------- Summary: end -------- 09:39:18 INFO - PROCESS | 1685 | 09:39:18 INFO - TEST-INFO | 1685: exit 0 09:39:18 INFO - TEST-OK | kraken | took 56746ms 09:39:18 INFO - SUITE-END | took 1170s 09:39:19 INFO - Completed test suite (00:19:30) 09:39:19 INFO - Working with test: dromaeo_css 09:39:19 INFO - Generating results file: dromaeo_css 09:39:19 INFO - Working with test: kraken 09:39:19 INFO - Generating results file: kraken 09:39:19 INFO - javascript benchmark 09:39:19 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:39:19 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 09:39:19 INFO - process_Request line: dromaeo_css 7365.00 graph.html#tests=[[72,64,59]] 09:39:19 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:39:19 INFO - process_Request line: kraken 1426.60 graph.html#tests=[[232,64,59]] 09:39:19 INFO - RETURN: dromaeo_css: 7365.00 09:39:19 INFO - RETURN: kraken: 1426.60 09:39:19 INFO - javascript benchmark 09:39:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3195.424823531764, 3486.3898817706895, 3499.0727457223857, 3505.3865573524627, 3537.673372485318, 5608.551315894729, 5620.1288800235925, 5691.7723291068305, 5694.629849059808, 5719.256496655439, 5350.384705758829, 5683.744231509581, 5693.601447898649, 5801.390853960336, 5840.182374467578, 5131.0, 5138.66598671087, 5159.32928719266, 5176.456472070435, 5300.443453437391, 5293.6294459387855, 5293.947060529384, 5300.337457817773, 5347.385050719178, 5366.91949620756, 5103.9489605103845, 5121.897562048757, 5195.324607801, 5197.0, 5245.344331958505, 5178.792848286102, 5183.714895680736, 5191.273221748958, 5202.959408118391, 5203.349581302337, 5122.359705036871, 5157.5616072633875, 5162.6128040397125, 5185.533302002837, 5215.739213039333, 5423.430539793344, 5514.751836167342, 5566.582506312002, 5643.0, 5723.942760572382, 6195.163652906845, 6200.0, 6206.348333425016, 6222.0355844844125, 6243.906341404882, 4533.773311334453, 6080.0575910733905, 6207.503399728011, 6259.624422534663, 6262.185915830958, 5704.543636509069, 5752.0, 5808.07070868659, 5817.360090390053, 5868.647881127189, 6257.0, 6267.6866156691985, 6278.560500764994, 6283.591566548251, 6298.527610429236, 5952.613080149777, 6007.429294217042, 6128.233970753656, 6137.0, 6140.232470941132, 6133.701927635152, 6182.629042257479, 6220.813375598739, 6226.782062627877, 6250.624962502265, 6159.353267906895, 6196.969015154896, 6204.5967012144065, 6209.0996805462955, 6225.097360882737, 5151.639385242997, 5270.604704647167, 6101.974255744823, 6220.129181914535, 6227.719752611143, 4883.682560633548, 4898.804047838046, 4904.754762261872, 4920.950790492049, 4937.975310123426, 4364.54172311909, 4380.080183161572, 4385.166818304576, 4406.7576283304725, 4446.5331140230455, 5478.506934375846, 5550.333959924754, 5566.415526369754, 5642.548596112301, 5652.576056795757, 6209.455963704371, 6226.59527130735, 6250.656213908279, 6252.781152659635, 6260.593061450991, 5598.916016259759, 5624.0, 5645.096784514458, 5657.236273103078, 5665.12190610456, 4431.8670439886855, 5322.760475778599, 5333.973330133324, 5375.9462405376225, 5394.811181608625, 6287.339829318039, 6288.339724328879, 6291.811245662638, 6301.842453938688, 6308.274548426898, 6012.669303188191, 6239.06414037898, 6242.094896240111, 6260.436560709558, 6262.499000079892, 6243.937560624518, 6262.46769024638, 6307.36926307384, 6307.590006649553, 6323.241211054703, 6097.756089756537, 6136.509079273558, 6138.109974053648, 6141.84645383869, 6146.508279337553, 5922.407759224216, 6005.309389420097, 6233.345498722751, 6247.12540244357, 6252.218472690914, 5812.273465816773, 6030.336662967158, 6070.1501789750355, 6079.088136779518, 6079.665618390854, 6049.969750151221, 6079.604825686316, 6081.0, 6081.239845019372, 6087.4521293083835, 5259.63182577216, 5515.641483303572, 5828.358880523224, 5905.881882362414, 5907.852303692442, 6058.606190597597, 6065.181200537871, 6067.484263837447, 6095.085737139288, 6107.4197951194565, 5913.0, 5929.288485381781, 5930.911036334544, 5935.317438494456, 5940.346561878277], "unit": "score", "name": "dojo.html", "value": 5645.967478965364}, {"lowerIsBetter": false, "replicates": [485.5751217684526, 566.9603127781054, 567.6765000851515, 601.1163589523401, 603.2670305578722, 29818.956336528197, 37727.0, 38036.61963380365, 38119.0, 38166.236675266475, 40594.98512537181, 40964.18071638566, 40989.7950510247, 41007.794961025225, 41010.7949460253, 9435.29235307353, 9630.75923101921, 9636.614535418576, 9639.807203855918, 9640.855387169198, 9514.619415223382, 9654.275929305295, 9669.371490853073, 9672.75818104546, 9680.419174849532, 9627.0, 9839.409635421862, 9844.31089823709, 9850.211983041376, 9860.802783944317, 9016.639334426614, 9150.130737579919, 9737.318387712863, 9796.853047204298, 9827.459489728099, 9606.27952903528, 9763.414195148314, 9775.560099795526, 9781.266405019582, 9784.755381115529, 39371.60628393708, 39657.40513892294, 39685.0, 39733.807985760184, 39837.80081099576, 3171.0, 3229.0635715642434, 3398.5242066110764, 3537.5224344713392, 3758.3610786166146, 28328.0, 29008.41983160346, 29703.70296297031, 29717.55423668647, 29747.70252297471, 2718.225305787841, 2771.7505424511687, 2828.60399544064, 2862.055521677841, 2872.3967966727014, 399551.0, 399970.0, 400207.0, 400432.9999999971, 400915.0, 61370.079448808305, 61677.76644467086, 62075.0, 62137.68931155315, 65528.344716553176, 69644.65177674181, 69651.65174174096, 69808.0, 69900.0, 69935.55869957592, 13474.461021559027, 16310.592235194215, 16506.2572184252, 16514.174291285628, 16540.007599544067, 12608.991280697523, 12686.0, 12688.49246030167, 12689.87310126887, 12700.237985720702, 16945.11932971398, 20405.387838364575, 20819.791802082087, 20837.37487875366, 20840.791592084188, 8752.51861147643, 8908.242799362128, 8914.153155450222, 8926.330525210635, 8950.955245223862, 821.2649678537795, 939.9859002114973, 947.7441090905417, 966.6133546581424, 1084.5444913136503, 27551.862240688668, 28383.716162838522, 28409.57385639217, 28659.28351791222, 28731.85634071858, 968.5835090910975, 996.3274789517047, 1044.1803184500284, 1080.7460246841986, 1147.984034129801, 12928.741425171444, 14379.209143497208, 14402.08731676222, 14556.0, 14626.707465850623, 538921.0, 538972.0, 540187.0, 540658.0, 540885.0, 50952.23571646428, 58370.12444813331, 59107.52231194254, 59139.0, 59222.40777592169, 1438.9424423022963, 1461.7734251191082, 1466.2961778346453, 1477.0768269831356, 1484.695637394353, 23767.881160594086, 28819.56770648484, 29418.705812941596, 30233.69766302309, 31388.0, 49726.502734973634, 50146.749266253435, 50223.74888125682, 50235.24647130223, 50421.74789126177, 387873.0, 390889.0, 391020.0, 391636.0, 392010.0, 31560.0, 31934.0, 32268.677313226566, 32395.35209295754, 32408.0, 43191.78404107959, 43462.56537434712, 43886.56113438825, 44339.33490997702, 44847.32729009002, 35284.0, 36436.95707302958, 36920.81539592392, 37043.62956370402, 37119.62880371162, 35788.82105589456, 36523.0, 36778.0, 36827.63172368242, 37053.44419833651, 36203.0, 36693.816530917175, 36882.8155859219, 36959.89120326394, 36990.0, 30023.3995320088, 30085.0, 32391.51412728858, 32587.0, 32633.836830815693, 24307.28471544417, 24568.0, 24591.0, 24667.4993754684, 24708.79950408399], "unit": "score", "name": "ext.html", "value": 19491.87400732379}, {"lowerIsBetter": false, "replicates": [334.07294757049203, 364.40056107702827, 369.593447208071, 370.2557858704005, 374.24962949286675, 15127.319270632821, 15446.61383465414, 18898.716519252222, 19454.610907781855, 20286.39140825774, 18864.24543018281, 18990.525236869085, 19170.90414547929, 19170.945597992104, 19245.0, 5186.922196167051, 5826.737796799154, 5830.621009634381, 5832.416758324176, 5861.706914654272, 4124.731892426983, 4321.286987647034, 5784.508316793057, 5913.14259432382, 6132.6933653317155, 6047.123167140762, 6267.686615669221, 6312.116303717483, 6327.303996560375, 6329.715162817684, 6247.562670613059, 6308.905366419507, 6316.0, 6317.05244213367, 6317.52618553608, 5212.895742085175, 5418.29562156921, 5421.430749771257, 5422.91865622016, 5523.806666766684, 23867.403314917094, 23892.163774267992, 24265.636015459782, 24815.50368992628, 24874.25377238686, 2342.7657234276435, 2384.6423036544484, 2525.4696513732138, 2599.2679653100763, 2638.2085374387607, 13916.025878188531, 14465.78301325481, 14628.853711462962, 14637.975341726084, 14660.486882959152, 1723.2073246306588, 1741.2077504735441, 1749.9387521436688, 1750.161683498341, 1759.1643969114568, 72784.99999999948, 80643.19356806416, 84869.57565212196, 85660.99999999937, 87404.0, 9938.453385063747, 10187.643432479828, 10721.249512534203, 10761.83857242142, 10860.294080884718, 12800.231986080866, 12898.03264755147, 12978.35108244584, 12981.0, 13014.544490942772, 8190.50856948585, 8212.137725538852, 8215.0, 8263.545504997162, 8303.377246706521, 8269.751907442786, 8275.50346979183, 8299.419040667095, 8376.455530390627, 8381.245687887997, 4368.584984426474, 4453.242948698729, 4458.509563947963, 4461.598456138963, 4485.529019452975, 1496.3416096917322, 1653.1238443624898, 1799.299661819568, 1834.6249371618785, 1848.279171123263, 917.3578495053489, 920.3695468604028, 925.0666107834658, 925.1026504290827, 965.6716716316486, 15413.383464661289, 17931.37240196587, 19854.801451985582, 20225.595488090155, 20805.479863003242, 440.9162259170747, 877.7673916412156, 926.5413620258015, 933.3466573398513, 1033.132168978062, 5439.972800135974, 5532.723363831792, 5966.463018328284, 6142.262928448526, 6145.846353841101, 627761.0, 706820.0, 721072.0, 732027.0, 754101.0, 108640.45679771708, 114247.0, 114868.0, 114910.85089149169, 115266.0, 490.6050629243422, 496.15405733224765, 507.12014654574284, 507.596460813654, 507.63957590111437, 10656.229380361317, 10872.26043832983, 10882.0, 11085.168612354268, 11129.443527823414, 45455.54544454646, 45930.0, 47040.7647961758, 48645.75677121733, 49162.0, 73222.0, 74027.8895816574, 74177.25822741704, 74673.0, 75056.62471687606, 7444.367228785397, 7487.042618165685, 7742.535447873146, 7789.766307010798, 7803.297703206738, 8512.829743405222, 9291.581878815334, 11454.599089031844, 11741.530338786695, 12068.698282543006, 8020.478668886503, 8831.116888311117, 9267.953660231655, 9903.55434005457, 10189.541470633987, 8851.33614978892, 9594.66418675343, 9725.978772228815, 10306.660848177105, 10650.573977041142, 8496.490210587384, 10540.367577945348, 10642.787144257227, 10706.518206680874, 10979.780404392028, 7547.05661792276, 7660.616969151632, 7685.654145563351, 7693.49992250502, 7709.0, 8696.999845017905, 9987.151092156957, 10322.535485903003, 10332.018458246455, 10505.212109092], "unit": "score", "name": "jquery.html", "value": 8655.786781019586}, {"lowerIsBetter": false, "replicates": [1345.3878485289194, 1516.0524672079948, 1558.8830837687174, 1786.5265704588287, 1943.9514012149702, 7924.960375198123, 8128.065272493657, 8635.740927772162, 8859.291256699466, 8859.335549833771, 8621.353398495123, 8651.870221946676, 8782.341324400664, 8792.340574456925, 8795.780105497366, 2214.6567282071305, 2272.4432514034083, 2490.1657944588583, 2522.8612426316545, 2526.4568117854656, 2472.24596498068, 2540.504601602688, 2544.160427059066, 2544.2748816587277, 2545.847249165047, 2436.4396188876567, 2513.6857892763405, 2515.5094756522485, 2516.006177559863, 2520.5085008423366, 2487.1046423287603, 2506.248125562333, 2526.7094284157374, 2528.304716203059, 2529.165375426114, 2491.638712386712, 2506.5112303100905, 2513.5349960257226, 2537.073968001666, 2537.6320433537217, 8851.557422128932, 8904.0, 8905.554722263925, 8910.643574257021, 8933.0, 5419.078756611346, 5528.612997090206, 5572.3034620672415, 5573.219749235111, 5574.637648552831, 3491.917505573255, 3628.183658676803, 3640.9453858192146, 3641.3081514512423, 3648.7445878788494, 911.9452832830051, 1062.1343604962008, 1071.1056268016216, 1092.1153865369076, 1098.1379617000696, 12172.452239649238, 12192.39038048094, 12210.938945305306, 12220.450079746433, 12229.266244025299, 4233.872983810491, 4942.777575009061, 4960.8759781005765, 5022.42242142151, 5023.397192336943, 10335.793284134275, 10371.377717336984, 10376.221783366127, 10378.42918639467, 10395.84406233907, 5579.469950354791, 5587.69267690281, 5598.300212473441, 5602.1036634138345, 5605.607607467438, 5062.240663900371, 5075.350511083912, 5078.111330517145, 5083.415407228142, 5086.0845047891, 3677.7793332400142, 3765.8493660253284, 3765.9246815063543, 3769.4911186989953, 4000.6199411055904, 1168.2523185161504, 1288.8037666586729, 1303.88916942061, 1325.3042152869627, 1331.40086960868, 1103.1450625765096, 1240.6774238698004, 1319.940602672882, 1342.4898538555287, 1374.1068305601439, 8061.556614386265, 8140.226678465537, 8149.51102933814, 8153.062397824327, 8167.63245653947, 1190.4047976011993, 1191.5531675621642, 1197.7963746163173, 1218.524775337619, 1239.0768877186554, 6175.135481032659, 6180.351063138306, 6222.502199824004, 6244.65654389013, 6247.187865577536, 10484.318519296221, 10948.178886583539, 10948.781024379468, 10952.780944381228, 10956.506957186946, 10366.948165259126, 10785.40680262593, 10795.352278863293, 10806.729831754268, 10821.188410869216, 8354.58227088643, 8364.038135614483, 8445.78885527879, 8452.0, 8452.239298463168, 7888.447808653569, 7902.24928631779, 7918.604069796372, 7933.8413231736195, 7974.5215287082965, 9391.060893910828, 9517.00071492511, 9776.071273229032, 9780.315377923476, 9789.75525611837, 9667.27495437859, 9993.350432221881, 10053.547590358305, 10107.19142468586, 10135.594576217163, 6302.527310451826, 7395.556266624019, 7398.0, 7483.102027756704, 7529.623518824146, 7263.16473605521, 7473.103227612722, 8220.712275070344, 8313.08556058821, 8314.168583141638, 7665.731699390494, 7682.961585192262, 7715.22847715224, 7807.141214466291, 7891.921080789349, 6506.284308725942, 7517.985072015208, 7626.809329766578, 7635.121960974559, 7731.884021739791, 7767.115219962657, 7790.220977902164, 7854.136045035156, 7861.017372828396, 7883.172266911892, 7089.149302083783, 7120.430365570845, 7147.892781608176, 7148.249433809375, 7162.2121566628675, 7179.282071792779, 7200.063991681048, 7243.022192004012, 7256.2743725629125, 7258.129024517092], "unit": "score", "name": "mootools.html", "value": 4996.497934481487}, {"lowerIsBetter": false, "replicates": [269.8583243797006, 306.82970951122127, 332.0221946367946, 335.4013086640346, 342.95027221052965, 6120.571559990803, 6340.365963403663, 6392.936070639293, 6406.743730250796, 6443.484521238302, 6245.875082498359, 6312.810615681526, 6325.114483972247, 6331.810045698625, 6355.3009168991375, 4454.420925279709, 4525.818967241306, 4609.709281401197, 4697.201475749113, 4707.858764237069, 4602.539746025387, 4619.422572178478, 4640.67515273931, 4648.000679853831, 4650.511696271894, 4574.5882870541645, 4576.473705523874, 4586.243269860469, 4592.5407459253975, 4608.262677971526, 4329.372241025049, 4498.212812757787, 4516.887077823064, 4598.494165641777, 4628.282616194495, 3974.364101743707, 3975.264576053439, 3977.5624681285035, 3980.542237642679, 3996.840126394941, 4728.219843725781, 5885.529157667376, 5900.409959004108, 5917.615355001911, 5923.703814809285, 5250.396204436501, 5371.570274378079, 5415.620906536544, 5426.050441172819, 5426.674399536001, 6568.211814582233, 6569.507286953498, 6570.704318305688, 6609.843277426479, 6623.847249643837, 3792.4880141180865, 4367.58507941745, 4463.174312752151, 4475.820967161309, 4478.932816007762, 6894.379505844498, 6998.125234345707, 7006.334398232159, 7009.404200642952, 7012.088428504313, 4429.53489883564, 4585.839495617704, 4588.472325682523, 4589.701669391479, 4593.517680643551, 4671.902103005791, 4675.5324467553655, 4680.251159814414, 4684.906301874012, 4697.412823397075, 5395.73021348931, 5428.782848686087, 5482.424345443751, 5487.780488780484, 5492.972535137299, 6496.675166241669, 6711.832204194934, 6773.966130169318, 6779.084823548857, 6784.626845523445, 1621.9675606487804, 1645.0294326347273, 1645.0540938960146, 1648.9917550412172, 1654.420952666557, 1072.8068947589509, 1078.2075174746556, 1088.1023155896416, 1100.7688385438987, 1104.2932523185168, 6215.751369945152, 6228.688565571703, 6230.813075607739, 6238.220222472191, 6260.5617606768, 5534.584906132056, 6119.969400152971, 6363.5545511813725, 6933.1333583302085, 6996.055532503047, 5039.3952725672425, 6203.8138855834395, 6205.565610407319, 6219.191505104398, 6221.720022598994, 3292.8024318540965, 3404.2340473393288, 3589.6769290763955, 3665.395209790408, 3689.6310368963427, 7328.560286382834, 7332.706691732271, 7339.302766237306, 7345.19202887682, 7347.742829000959, 6943.583384996917, 7231.927680723231, 7234.204237533867, 7235.421166306683, 7240.094988126484, 7296.489245752959, 7302.452316076316, 7311.305425984523, 7329.780106596811, 7343.559386436831, 7007.474439416962, 7007.7196912122945, 7010.193827709674, 7017.298270172942, 7019.3682568569075, 6564.770233042012, 6890.0, 6905.37851593359, 6917.412019978157, 6931.480138989697, 7315.8536829264185, 7334.596597187206, 7334.96332518334, 7339.70641174347, 7348.632568371453, 4470.664700147437, 4517.277235642281, 4520.570545798144, 4521.09578084391, 4531.660125490535, 6769.593824370553, 6992.3357281058215, 7048.0, 7059.2940705928995, 7070.858582828418, 6721.361470660279, 6731.899021514779, 6732.495062870402, 6806.400495883365, 6829.829254268484, 6761.594304341756, 6795.0, 6804.96597517029, 6807.455403567604, 6814.625195614289, 5983.534034161658, 6398.0, 6449.226092868698, 6641.734330626721, 6648.407121222824, 6536.575122617015, 6621.900671489835, 6677.2655007948115, 6721.126253587003, 6732.596044237362, 6656.966715166393, 6667.49993750461, 6689.866202675822, 6695.230048544479, 6703.229128650268], "unit": "score", "name": "prototype.html", "value": 4932.948690972707}, {"lowerIsBetter": false, "replicates": [680.5916450129922, 695.8747425463416, 727.9490435669502, 729.9124105107388, 747.484235877245, 14613.196274204927, 14769.926150369232, 14791.926040369797, 14802.925985370084, 14805.555833325005, 13275.734485310288, 13320.067595268336, 13329.933350333258, 13331.466741330336, 13334.933325333359, 676.0737789228764, 710.3713213805793, 848.4739461533827, 877.8814859993915, 883.5273128876053, 862.1249431826701, 872.1714371347214, 876.7983363826323, 876.8158686675774, 877.4603618774439, 726.7746998430504, 748.7491690283762, 933.4212788071405, 936.3913456253456, 937.8265020971108, 897.5691667999364, 927.1655510040949, 927.452802846324, 930.4696323095839, 932.1703683721494, 692.0657112897599, 692.8060143159923, 695.3185877839695, 695.6626036372355, 698.0471656189316, 14092.718145637029, 14135.293235338295, 14156.646083847883, 14162.858371416258, 14193.0, 12460.252384856938, 12551.121421500498, 12570.0, 12754.80867786984, 12812.743745125139, 9993.450360230185, 10023.548940297704, 10026.749331266703, 10051.0, 10066.647667331608, 3497.930041399183, 3530.135116896351, 3531.5585551806025, 3535.5757309122814, 3538.3630946429635, 73461.6326918362, 75466.0, 78316.0, 78620.0, 78730.60634696847, 9492.620295188253, 9497.14525692691, 9657.082577155159, 9693.854592181124, 10264.43545604984, 21403.67894481584, 21447.463813404476, 21454.141834326452, 21463.892680536497, 21477.248296309866, 7011.158660960616, 7334.119905611361, 7337.889931650922, 7376.594287314142, 7407.407407407394, 4062.8171732272117, 4069.0, 4099.0162361033135, 4108.239975604463, 4141.233871733739, 3480.147363895811, 3519.0, 3523.5067090607336, 3523.71810255179, 3531.7638826411066, 649.4447247603325, 651.3811878715239, 653.3792896748108, 662.5163630549725, 674.3762020131359, 565.4486875296635, 585.6515373352848, 614.7694614519555, 619.3094699410125, 633.9080833279148, 8374.916250837536, 8389.119142490074, 8501.872471912926, 8553.44402613828, 8690.695825646071, 526.2264471227289, 585.7861880413618, 604.2054698072021, 611.6116266171014, 654.4633400611548, 3573.8525178965306, 3734.2158146788934, 3908.472356231929, 4065.8170382332864, 4081.653059489977, 79105.0, 80945.19054809377, 81292.55419602954, 82531.7620235697, 82724.0, 74634.62682686551, 75084.0, 75326.62336688282, 75567.62216188884, 75782.0, 72386.63806680933, 72578.63710681413, 72684.54630907458, 73099.17252068635, 73445.26554734384, 8766.868496972678, 8795.934518269345, 8904.910950890408, 8973.685920992733, 9104.317176211944, 47848.760756195996, 48847.267290991374, 49427.752861235465, 49473.0, 50754.74622626863, 62486.68756656188, 68276.0, 68689.65655171692, 69158.65420672865, 70430.64784676043, 10572.312799667998, 10815.405152716361, 10910.781784364426, 11162.330260184415, 11238.988491035847, 9917.30578859473, 10676.199285053497, 11895.881041189823, 11969.760604787682, 12220.0, 11151.609693660683, 11299.0, 11578.015868651259, 11739.772018262185, 11961.700957476132, 11190.0, 11442.542298307975, 11479.942600286946, 11907.761844762883, 11962.820557691815, 8135.150670784552, 10395.636152734618, 10656.730044059846, 11021.779564408507, 11782.057435405319, 7997.680092796223, 9274.20362273579, 9490.620375185192, 9671.516424178622, 9968.800623987625, 8605.311575073854, 9678.56446459925, 9710.368826026568, 9817.754556136153, 9945.850812237966], "unit": "score", "name": "yui.html", "value": 6797.203034356234}], "name": "dromaeo_css", "value": 7364.997114664804}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 110.0, 111.0, 111.0, 112.0, 117.0, 111.0, 109.0, 112.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [157.0, 121.0, 144.0, 172.0, 146.0, 148.0, 156.0, 145.0, 173.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 147.9}, {"lowerIsBetter": true, "replicates": [184.0, 183.0, 187.0, 184.0, 188.0, 223.0, 185.0, 181.0, 180.0, 180.0], "unit": "score", "name": "audio-dft", "value": 187.5}, {"lowerIsBetter": true, "replicates": [73.0, 77.0, 89.0, 78.0, 57.0, 59.0, 58.0, 81.0, 81.0, 65.0], "unit": "score", "name": "audio-fft", "value": 71.8}, {"lowerIsBetter": true, "replicates": [90.0, 94.0, 124.0, 79.0, 80.0, 84.0, 108.0, 77.0, 79.0, 111.0], "unit": "score", "name": "audio-oscillator", "value": 92.6}, {"lowerIsBetter": true, "replicates": [84.0, 90.0, 87.0, 93.0, 83.0, 89.0, 85.0, 90.0, 84.0, 89.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.4}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 93.0}, {"lowerIsBetter": true, "replicates": [85.0, 88.0, 86.0, 85.0, 87.0, 86.0, 91.0, 86.0, 85.0, 91.0], "unit": "score", "name": "imaging-desaturate", "value": 87.0}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 57.0, 55.0, 54.0, 54.0, 54.0, 64.0, 55.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 55.6}, {"lowerIsBetter": true, "replicates": [66.0, 61.0, 68.0, 77.0, 68.0, 67.0, 66.0, 66.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.1}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 88.0, 82.0, 83.0, 82.0, 83.0, 86.0, 106.0, 106.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.3}, {"lowerIsBetter": true, "replicates": [127.0, 124.0, 126.0, 123.0, 121.0, 127.0, 125.0, 126.0, 122.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.2}, {"lowerIsBetter": true, "replicates": [162.0, 176.0, 175.0, 136.0, 139.0, 127.0, 140.0, 135.0, 136.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.2}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0, 68.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.8}], "name": "kraken", "value": 1426.6000000000001}]} 09:39:19 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1426.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7365.00"}}} 09:39:19 INFO - Return code: 0 09:39:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:39:19 INFO - # TBPL SUCCESS # 09:39:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:19 INFO - 2016-02-16 09:39:19.717 screenresolution[1689:10142] starting screenresolution argv=screenresolution get 09:39:19 INFO - 2016-02-16 09:39:19.720 screenresolution[1689:10142] Display 0: 1600x1200x32@60 09:39:19 INFO - 2016-02-16 09:39:19.726 screenresolution[1690:10145] starting screenresolution argv=screenresolution list 09:39:19 INFO - Available Modes on Display 0 09:39:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:19 INFO - Intel Iris: 09:39:19 INFO - Chipset Model: Intel Iris 09:39:19 INFO - Type: GPU 09:39:19 INFO - Bus: Built-In 09:39:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:19 INFO - Vendor: Intel (0x8086) 09:39:19 INFO - Device ID: 0x0a2e 09:39:19 INFO - Revision ID: 0x0009 09:39:19 INFO - Displays: 09:39:19 INFO - Display: 09:39:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:19 INFO - Display Serial Number: 1600x1200 60 09:39:19 INFO - Main Display: Yes 09:39:19 INFO - Mirror: Off 09:39:19 INFO - Online: Yes 09:39:19 INFO - Rotation: Supported 09:39:19 INFO - Return code: 0 09:39:19 INFO - Running post-action listener: _resource_record_post_action 09:39:19 INFO - Running post-run listener: _resource_record_post_run 09:39:21 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 39515648; Write bytes: 707204608; Read time: 427; Write time: 1812 09:39:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397472256; Write bytes: 390957056; Read time: 30260; Write time: 645 09:39:21 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 37565952; Write bytes: 300498432; Read time: 404; Write time: 1123 09:39:21 INFO - Running post-run listener: _upload_blobber_files 09:39:21 INFO - Blob upload gear active. 09:39:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:21 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 09:39:21 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:39:21 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:39:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:39:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:22 INFO - (blobuploader) - INFO - Done attempting. 09:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:24 INFO - (blobuploader) - INFO - Done attempting. 09:39:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:24 INFO - Return code: 0 09:39:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8472cb27580e8c744494b996fa680f692109ebfef57acbc26e56586b59f93f7272e466b3047278c23d77f176a8a7af0b310cc6ad266a6da21bcb271c9b873f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e93467e99d56d8be4a3acab9b40cdd72c4c10e346c7b9267fdfb94a149f2ed96b05bf61e97239b9f6aed51d6e66f0194f6b98b771177935f4162698f1428f900"} 09:39:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:24 INFO - Contents: 09:39:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8472cb27580e8c744494b996fa680f692109ebfef57acbc26e56586b59f93f7272e466b3047278c23d77f176a8a7af0b310cc6ad266a6da21bcb271c9b873f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e93467e99d56d8be4a3acab9b40cdd72c4c10e346c7b9267fdfb94a149f2ed96b05bf61e97239b9f6aed51d6e66f0194f6b98b771177935f4162698f1428f900"} 09:39:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:24 INFO - Copying logs to upload dir... 09:39:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.461108 ========= master_lag: 6.80 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-02-16 09:39:30.502078) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-02-16 09:39:30.503206) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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/Fx-Team/sha512/c8472cb27580e8c744494b996fa680f692109ebfef57acbc26e56586b59f93f7272e466b3047278c23d77f176a8a7af0b310cc6ad266a6da21bcb271c9b873f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e93467e99d56d8be4a3acab9b40cdd72c4c10e346c7b9267fdfb94a149f2ed96b05bf61e97239b9f6aed51d6e66f0194f6b98b771177935f4162698f1428f900"} build_url:https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/LijX8chtRqCD6UC9zKl8TA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8472cb27580e8c744494b996fa680f692109ebfef57acbc26e56586b59f93f7272e466b3047278c23d77f176a8a7af0b310cc6ad266a6da21bcb271c9b873f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e93467e99d56d8be4a3acab9b40cdd72c4c10e346c7b9267fdfb94a149f2ed96b05bf61e97239b9f6aed51d6e66f0194f6b98b771177935f4162698f1428f900"}' ========= master_lag: 6.80 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-02-16 09:39:37.317395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:39:37.317739) ========= 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.QtOeJ1HuhC/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.BfnKn9GR7a/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.004834 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 09:39:37.428942) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-02-16 09:39:37.429247) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-02-16 09:39:40.805611) ========= ========= Total master_lag: 13.88 =========