builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0004 starttime: 1456526726.84 results: success (0) buildid: 20160226124627 builduid: a29c9152f38d4332abb0b6771e89c720 revision: b5c0cd56381547fe527c724d86eb955c209e0a6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:26.838964) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:26.840013) ========= ========= Started set props: basedir (results: 0, elapsed: 8 secs) (at 2016-02-26 14:45:26.840358) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005727 basedir: '/builds/slave/test' ========= master_lag: 8.00 ========= ========= Finished set props: basedir (results: 0, elapsed: 8 secs) (at 2016-02-26 14:45:34.850813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-02-26 14:45:34.857162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-02-26 14:45:45.719382) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:45.719669) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005763 ========= master_lag: 0.58 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:46.302722) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:46.303091) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 14:45:46.303482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2016-02-26 14:45:46.303792) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-26 14:45:46-- 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% 156M=0s 2016-02-26 14:45:46 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532725 ========= master_lag: 12.89 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2016-02-26 14:45:59.723118) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-02-26 14:45:59.723446) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.019529 ========= master_lag: 2.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-02-26 14:46:01.742875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-02-26 14:46:01.743245) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b5c0cd56381547fe527c724d86eb955c209e0a6e --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 b5c0cd56381547fe527c724d86eb955c209e0a6e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-26 14:46:01,837 truncating revision to first 12 chars 2016-02-26 14:46:01,837 Setting DEBUG logging. 2016-02-26 14:46:01,837 attempt 1/10 2016-02-26 14:46:01,837 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b5c0cd563815?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-26 14:46:02,941 unpacking tar archive at: fx-team-b5c0cd563815/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337786 ========= master_lag: 3.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-02-26 14:46:06.634612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 2 secs) (at 2016-02-26 14:46:06.634952) ========= echo b5c0cd56381547fe527c724d86eb955c209e0a6e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'b5c0cd56381547fe527c724d86eb955c209e0a6e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 b5c0cd56381547fe527c724d86eb955c209e0a6e program finished with exit code 0 elapsedTime=0.005203 script_repo_revision: 'b5c0cd56381547fe527c724d86eb955c209e0a6e' ========= master_lag: 2.30 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 2 secs) (at 2016-02-26 14:46:08.937168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-02-26 14:46:08.937516) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-02-26 14:46:11.954451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-02-26 14:46:11.954782) ========= /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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:12 INFO - MultiFileLogger online at 20160226 14:46:12 in /builds/slave/test 14:46:12 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 14:46:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:46:12 INFO - {'append_to_log': False, 14:46:12 INFO - 'base_work_dir': '/builds/slave/test', 14:46:12 INFO - 'blob_upload_branch': 'Fx-Team', 14:46:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:46:12 INFO - 'branch': 'Fx-Team', 14:46:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:46:12 INFO - 'config_files': ('talos/mac_config.py',), 14:46:12 INFO - 'default_actions': ('clobber', 14:46:12 INFO - 'read-buildbot-config', 14:46:12 INFO - 'download-and-extract', 14:46:12 INFO - 'populate-webroot', 14:46:12 INFO - 'create-virtualenv', 14:46:12 INFO - 'install', 14:46:12 INFO - 'run-tests'), 14:46:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:46:12 INFO - 'download_minidump_stackwalk': True, 14:46:12 INFO - 'download_symbols': 'ondemand', 14:46:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:46:12 INFO - 'tooltool.py': '/tools/tooltool.py', 14:46:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:46:12 INFO - '/tools/misc-python/virtualenv.py')}, 14:46:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:46:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:46:12 INFO - 'installer_path': 'installer.exe', 14:46:12 INFO - 'log_level': 'info', 14:46:12 INFO - 'log_name': 'talos', 14:46:12 INFO - 'log_to_console': True, 14:46:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:46:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:46:12 INFO - 'opt_config_files': (), 14:46:12 INFO - 'pip_index': False, 14:46:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:46:12 INFO - 'cmd': ('bash', 14:46:12 INFO - '-c', 14:46:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:46:12 INFO - 'enabled': True, 14:46:12 INFO - 'halt_on_failure': False, 14:46:12 INFO - 'name': 'check_screen_resolution'},), 14:46:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:46:12 INFO - 'cmd': ('bash', 14:46:12 INFO - '-c', 14:46:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:46:12 INFO - 'enabled': True, 14:46:12 INFO - 'halt_on_failure': False, 14:46:12 INFO - 'name': 'check_screen_resolution'},), 14:46:12 INFO - 'run_cmd_checks_enabled': True, 14:46:12 INFO - 'sps_profile': False, 14:46:12 INFO - 'sps_profile_interval': 0, 14:46:12 INFO - 'suite': 'dromaeojs', 14:46:12 INFO - 'system_bits': '32', 14:46:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:46:12 INFO - 'title': 't-yosemite-r7-0004', 14:46:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:46:12 INFO - 'use_talos_json': True, 14:46:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:46:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:46:12 INFO - 'work_dir': 'build'} 14:46:12 INFO - ##### 14:46:12 INFO - ##### Running clobber step. 14:46:12 INFO - ##### 14:46:12 INFO - Running pre-action listener: _resource_record_pre_action 14:46:12 INFO - Running main action method: clobber 14:46:12 INFO - rmtree: /builds/slave/test/build 14:46:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:46:13 INFO - Running post-action listener: _resource_record_post_action 14:46:13 INFO - ##### 14:46:13 INFO - ##### Running read-buildbot-config step. 14:46:13 INFO - ##### 14:46:13 INFO - Running pre-action listener: _resource_record_pre_action 14:46:13 INFO - Running main action method: read_buildbot_config 14:46:13 INFO - Using buildbot properties: 14:46:13 INFO - { 14:46:13 INFO - "project": "", 14:46:13 INFO - "product": "firefox", 14:46:13 INFO - "script_repo_revision": "production", 14:46:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:46:13 INFO - "repository": "", 14:46:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:46:13 INFO - "buildid": "20160226124627", 14:46:13 INFO - "pgo_build": "False", 14:46:13 INFO - "basedir": "/builds/slave/test", 14:46:13 INFO - "buildnumber": 510, 14:46:13 INFO - "slavename": "t-yosemite-r7-0004", 14:46:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:46:13 INFO - "platform": "yosemite_r7", 14:46:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:46:13 INFO - "branch": "fx-team", 14:46:13 INFO - "repo_path": "integration/fx-team", 14:46:13 INFO - "revision": "b5c0cd56381547fe527c724d86eb955c209e0a6e", 14:46:13 INFO - "stage_platform": "macosx64", 14:46:13 INFO - "builduid": "a29c9152f38d4332abb0b6771e89c720", 14:46:13 INFO - "slavebuilddir": "test" 14:46:13 INFO - } 14:46:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 14:46:13 INFO - Running post-action listener: _resource_record_post_action 14:46:13 INFO - ##### 14:46:13 INFO - ##### Running download-and-extract step. 14:46:13 INFO - ##### 14:46:13 INFO - Running pre-action listener: _resource_record_pre_action 14:46:13 INFO - Running main action method: download_and_extract 14:46:13 INFO - mkdir: /builds/slave/test/build/tests 14:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:13 INFO - https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 14:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/test_packages.json 14:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/test_packages.json 14:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 14:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 14:46:14 INFO - Downloaded 1277 bytes. 14:46:14 INFO - Reading from file /builds/slave/test/build/test_packages.json 14:46:14 INFO - Using the following test package requirements: 14:46:14 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 14:46:14 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 14:46:14 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 14:46:14 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'jsshell-mac.zip'], 14:46:14 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 14:46:14 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 14:46:14 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 14:46:14 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 14:46:14 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 14:46:14 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 14:46:14 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 14:46:14 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 14:46:14 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:14 INFO - https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 14:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 14:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:17 INFO - Downloaded 17830415 bytes. 14:46:17 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'] 14:46:17 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 14:46:21 INFO - Return code: 0 14:46:21 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 14:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:21 INFO - https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:46:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 14:46:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 14:46:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:22 INFO - Downloaded 17830415 bytes. 14:46:22 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'] 14:46:22 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 14:46:25 INFO - Return code: 0 14:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:25 INFO - https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:46:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 14:46:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 14:46:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:26 INFO - Downloaded 10713410 bytes. 14:46:26 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'] 14:46:26 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 14:46:26 INFO - Return code: 0 14:46:26 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')]} 14:46:26 INFO - https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:46:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 14:46:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 14:46:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 14:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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 14:46:29 INFO - Downloaded 111468925 bytes. 14:46:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 14:46:29 INFO - mkdir: /builds/slave/test/properties 14:46:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:46:29 INFO - Writing to file /builds/slave/test/properties/build_url 14:46:29 INFO - Contents: 14:46:29 INFO - build_url:https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 14:46:31 INFO - Running post-action listener: _resource_record_post_action 14:46:31 INFO - Running post-action listener: set_extra_try_arguments 14:46:31 INFO - ##### 14:46:31 INFO - ##### Running populate-webroot step. 14:46:31 INFO - ##### 14:46:31 INFO - Running pre-action listener: _resource_record_pre_action 14:46:31 INFO - Running main action method: populate_webroot 14:46:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:46:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:46:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:46:31 INFO - '--tppagecycles', 14:46:31 INFO - '7'], 14:46:31 INFO - 'tests': ['tsvgm']}}, 14:46:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:46:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:46:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 14:46:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:46:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:46:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:46:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'tests': ['damp', 'tps']}, 14:46:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['damp', 'tps']}, 14:46:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:46:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:46:31 INFO - 'other': {'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'other_l64': {'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'other_nol64': {'tests': ['a11yr', 14:46:31 INFO - 'ts_paint', 14:46:31 INFO - 'tpaint', 14:46:31 INFO - 'sessionrestore', 14:46:31 INFO - 'sessionrestore_no_auto_restore']}, 14:46:31 INFO - 'svgr': {'tests': ['tsvgx', 14:46:31 INFO - 'tsvgr_opacity', 14:46:31 INFO - 'tart', 14:46:31 INFO - 'tscrollx', 14:46:31 INFO - 'cart']}, 14:46:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['tsvgx', 14:46:31 INFO - 'tsvgr_opacity', 14:46:31 INFO - 'tart', 14:46:31 INFO - 'tscrollx', 14:46:31 INFO - 'cart']}, 14:46:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'tests': ['tp5o']}, 14:46:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'talos_options': ['--e10s'], 14:46:31 INFO - 'tests': ['tp5o']}, 14:46:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'talos_options': ['--xperf_path', 14:46:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:46:31 INFO - 'tests': ['tp5n']}, 14:46:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:46:31 INFO - 'talos_options': ['--e10s', 14:46:31 INFO - '--xperf_path', 14:46:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:46:31 INFO - 'tests': ['tp5n']}}, 14:46:31 INFO - 'talos.zip': {'path': '', 14:46:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:46:31 INFO - Running post-action listener: _resource_record_post_action 14:46:31 INFO - ##### 14:46:31 INFO - ##### Running create-virtualenv step. 14:46:31 INFO - ##### 14:46:31 INFO - Running pre-action listener: _resource_record_pre_action 14:46:31 INFO - Running main action method: create_virtualenv 14:46:31 INFO - Creating virtualenv /builds/slave/test/build/venv 14:46:31 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 14:46:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:46:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:46:31 INFO - Using real prefix '/tools/python27' 14:46:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:46:31 INFO - Installing distribute.............................................................................................................................................................................................done. 14:46:34 INFO - Installing pip.................done. 14:46:34 INFO - Return code: 0 14:46:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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')]} 14:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:34 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:34 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 14:46:34 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 14:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:34 INFO - 'HOME': '/Users/cltbld', 14:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:34 INFO - 'LOGNAME': 'cltbld', 14:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:34 INFO - 'MOZ_NO_REMOTE': '1', 14:46:34 INFO - 'NO_EM_RESTART': '1', 14:46:34 INFO - 'PAGER': '/bin/cat', 14:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:34 INFO - 'PWD': '/builds/slave/test', 14:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:34 INFO - 'SHELL': '/bin/bash', 14:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:34 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:34 INFO - 'USER': 'cltbld', 14:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:34 INFO - 'XPC_FLAGS': '0x0', 14:46:34 INFO - 'XPC_SERVICE_NAME': '0', 14:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:35 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 14:46:35 INFO - Cleaning up... 14:46:35 INFO - Return code: 0 14:46:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:35 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:35 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 14:46:35 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 14:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:35 INFO - 'HOME': '/Users/cltbld', 14:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:35 INFO - 'LOGNAME': 'cltbld', 14:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:35 INFO - 'MOZ_NO_REMOTE': '1', 14:46:35 INFO - 'NO_EM_RESTART': '1', 14:46:35 INFO - 'PAGER': '/bin/cat', 14:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:35 INFO - 'PWD': '/builds/slave/test', 14:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:35 INFO - 'SHELL': '/bin/bash', 14:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:35 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:35 INFO - 'USER': 'cltbld', 14:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:35 INFO - 'XPC_FLAGS': '0x0', 14:46:35 INFO - 'XPC_SERVICE_NAME': '0', 14:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:35 INFO - Downloading/unpacking psutil>=0.7.1 14:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:46:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:46:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:46:38 INFO - Installing collected packages: psutil 14:46:38 INFO - Running setup.py install for psutil 14:46:38 INFO - building 'psutil._psutil_osx' extension 14:46:38 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 14:46:38 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 14:46:38 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 14:46:38 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 14:46:38 INFO - building 'psutil._psutil_posix' extension 14:46:38 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 14:46:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:46:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:46:38 INFO - ^ 14:46:38 INFO - 1 warning generated. 14:46:38 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 14:46:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:46:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:46:39 INFO - Successfully installed psutil 14:46:39 INFO - Cleaning up... 14:46:39 INFO - Return code: 0 14:46:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:39 INFO - 'HOME': '/Users/cltbld', 14:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:39 INFO - 'LOGNAME': 'cltbld', 14:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:39 INFO - 'MOZ_NO_REMOTE': '1', 14:46:39 INFO - 'NO_EM_RESTART': '1', 14:46:39 INFO - 'PAGER': '/bin/cat', 14:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:39 INFO - 'PWD': '/builds/slave/test', 14:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:39 INFO - 'SHELL': '/bin/bash', 14:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:39 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:39 INFO - 'USER': 'cltbld', 14:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:39 INFO - 'XPC_FLAGS': '0x0', 14:46:39 INFO - 'XPC_SERVICE_NAME': '0', 14:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:46:41 INFO - Installing collected packages: mozsystemmonitor 14:46:41 INFO - Running setup.py install for mozsystemmonitor 14:46:42 INFO - Successfully installed mozsystemmonitor 14:46:42 INFO - Cleaning up... 14:46:42 INFO - Return code: 0 14:46:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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')]} 14:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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', '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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:46: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 blobuploader==1.2.4 14:46:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:42 INFO - 'HOME': '/Users/cltbld', 14:46:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:42 INFO - 'LOGNAME': 'cltbld', 14:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:42 INFO - 'MOZ_NO_REMOTE': '1', 14:46:42 INFO - 'NO_EM_RESTART': '1', 14:46:42 INFO - 'PAGER': '/bin/cat', 14:46:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:42 INFO - 'PWD': '/builds/slave/test', 14:46:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:42 INFO - 'SHELL': '/bin/bash', 14:46:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:42 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:42 INFO - 'USER': 'cltbld', 14:46:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:42 INFO - 'XPC_FLAGS': '0x0', 14:46:42 INFO - 'XPC_SERVICE_NAME': '0', 14:46:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:42 INFO - Downloading/unpacking blobuploader==1.2.4 14:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:44 INFO - Downloading blobuploader-1.2.4.tar.gz 14:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:46:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:46:44 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 14:46:44 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 14:46:44 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 14:46:44 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 14:46:44 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 14:46:44 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 14:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:46:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:45 INFO - Downloading docopt-0.6.1.tar.gz 14:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:46:46 INFO - Installing collected packages: blobuploader, requests, docopt 14:46:46 INFO - Running setup.py install for blobuploader 14:46:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:46:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:46:46 INFO - Running setup.py install for requests 14:46:46 INFO - Running setup.py install for docopt 14:46:46 INFO - Successfully installed blobuploader requests docopt 14:46:46 INFO - Cleaning up... 14:46:46 INFO - Return code: 0 14:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46: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')]} 14:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:46 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:46 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 14:46:46 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 14:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:46 INFO - 'HOME': '/Users/cltbld', 14:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:46 INFO - 'LOGNAME': 'cltbld', 14:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:46 INFO - 'MOZ_NO_REMOTE': '1', 14:46:46 INFO - 'NO_EM_RESTART': '1', 14:46:46 INFO - 'PAGER': '/bin/cat', 14:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:46 INFO - 'PWD': '/builds/slave/test', 14:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:46 INFO - 'SHELL': '/bin/bash', 14:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:46 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:46 INFO - 'USER': 'cltbld', 14:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:46 INFO - 'XPC_FLAGS': '0x0', 14:46:46 INFO - 'XPC_SERVICE_NAME': '0', 14:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-TqzlJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-S_aIZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-L5Q0uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-3dY5R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-_o3NgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-PAftRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-YVFjFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-XlNUzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:47 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-LN8tGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-VSxZhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Be764l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-EZ9yET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-XxZeWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-OzIQYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-XsA_IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-A1T37x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:48 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-cT59gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:46:48 INFO - Running setup.py install for manifestparser 14:46:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:46:49 INFO - Running setup.py install for mozcrash 14:46:49 INFO - Running setup.py install for mozdebug 14:46:49 INFO - Running setup.py install for mozdevice 14:46:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:46:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:46:49 INFO - Running setup.py install for mozfile 14:46:49 INFO - Running setup.py install for mozhttpd 14:46:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:46:49 INFO - Running setup.py install for mozinfo 14:46:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:46:49 INFO - Running setup.py install for mozInstall 14:46:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Running setup.py install for mozleak 14:46:50 INFO - Running setup.py install for mozlog 14:46:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Running setup.py install for moznetwork 14:46:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Running setup.py install for mozprocess 14:46:50 INFO - Running setup.py install for mozprofile 14:46:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:46:50 INFO - Running setup.py install for mozrunner 14:46:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:46:51 INFO - Running setup.py install for mozscreenshot 14:46:51 INFO - Running setup.py install for moztest 14:46:51 INFO - Running setup.py install for mozversion 14:46:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:46:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:46:51 INFO - Cleaning up... 14:46:51 INFO - Return code: 0 14:46:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:51 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:51 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 14:46:51 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 14:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:51 INFO - 'HOME': '/Users/cltbld', 14:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:51 INFO - 'LOGNAME': 'cltbld', 14:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:51 INFO - 'MOZ_NO_REMOTE': '1', 14:46:51 INFO - 'NO_EM_RESTART': '1', 14:46:51 INFO - 'PAGER': '/bin/cat', 14:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:51 INFO - 'PWD': '/builds/slave/test', 14:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:51 INFO - 'SHELL': '/bin/bash', 14:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:51 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:51 INFO - 'USER': 'cltbld', 14:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:51 INFO - 'XPC_FLAGS': '0x0', 14:46:51 INFO - 'XPC_SERVICE_NAME': '0', 14:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-hYMhHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:51 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)) 14:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-eWWVvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:51 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)) 14:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-1AI1xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-v693nS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-WzkGt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-RTWBTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-cxQA2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-4ssoSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-snR9uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-PWo_jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-UGfRRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:52 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)) 14:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-D411Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:53 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)) 14:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-mCSvQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:53 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)) 14:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-gr1bcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:53 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)) 14:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-rW_BbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:53 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)) 14:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-2bYuP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:53 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)) 14:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-5YVHft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:53 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)) 14:46:53 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)) 14:46:53 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)) 14:46:53 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)) 14:46:53 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)) 14:46:53 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)) 14:46:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:46: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 14:46: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 14:46: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 14:46: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 14:46: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 14:46: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 14:46:55 INFO - Downloading blessings-1.6.tar.gz 14:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:46:56 INFO - Installing collected packages: blessings 14:46:56 INFO - Running setup.py install for blessings 14:46:56 INFO - Successfully installed blessings 14:46:56 INFO - Cleaning up... 14:46:56 INFO - Return code: 0 14:46:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:46:56 INFO - Reading from file tmpfile_stdout 14:46:56 INFO - Current package versions: 14:46:56 INFO - blessings == 1.6 14:46:56 INFO - blobuploader == 1.2.4 14:46:56 INFO - docopt == 0.6.1 14:46:56 INFO - manifestparser == 1.1 14:46:56 INFO - mozInstall == 1.12 14:46:56 INFO - mozcrash == 0.16 14:46:56 INFO - mozdebug == 0.1 14:46:56 INFO - mozdevice == 0.48 14:46:56 INFO - mozfile == 1.2 14:46:56 INFO - mozhttpd == 0.7 14:46:56 INFO - mozinfo == 0.9 14:46:56 INFO - mozleak == 0.1 14:46:56 INFO - mozlog == 3.1 14:46:56 INFO - moznetwork == 0.27 14:46:56 INFO - mozprocess == 0.22 14:46:56 INFO - mozprofile == 0.28 14:46:56 INFO - mozrunner == 6.11 14:46:56 INFO - mozscreenshot == 0.1 14:46:56 INFO - mozsystemmonitor == 0.0 14:46:56 INFO - moztest == 0.7 14:46:56 INFO - mozversion == 1.4 14:46:56 INFO - psutil == 3.1.1 14:46:56 INFO - requests == 1.2.3 14:46:56 INFO - wsgiref == 0.1.2 14:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:56 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:56 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 14:46:56 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 14:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:56 INFO - 'HOME': '/Users/cltbld', 14:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:56 INFO - 'LOGNAME': 'cltbld', 14:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:56 INFO - 'MOZ_NO_REMOTE': '1', 14:46:56 INFO - 'NO_EM_RESTART': '1', 14:46:56 INFO - 'PAGER': '/bin/cat', 14:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:56 INFO - 'PWD': '/builds/slave/test', 14:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:56 INFO - 'SHELL': '/bin/bash', 14:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:56 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:56 INFO - 'USER': 'cltbld', 14:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:56 INFO - 'XPC_FLAGS': '0x0', 14:46:56 INFO - 'XPC_SERVICE_NAME': '0', 14:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 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)) 14:46:56 INFO - Cleaning up... 14:46:56 INFO - Return code: 0 14:46:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:46:56 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:46:56 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 14:46:56 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 14:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:56 INFO - 'HOME': '/Users/cltbld', 14:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:56 INFO - 'LOGNAME': 'cltbld', 14:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:56 INFO - 'MOZ_NO_REMOTE': '1', 14:46:56 INFO - 'NO_EM_RESTART': '1', 14:46:56 INFO - 'PAGER': '/bin/cat', 14:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:56 INFO - 'PWD': '/builds/slave/test', 14:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:56 INFO - 'SHELL': '/bin/bash', 14:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:46:56 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:56 INFO - 'USER': 'cltbld', 14:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:56 INFO - 'XPC_FLAGS': '0x0', 14:46:56 INFO - 'XPC_SERVICE_NAME': '0', 14:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:57 INFO - Downloading/unpacking jsonschema 14:46:57 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 14:46:57 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 14:46:57 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 14:46:57 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 14:46:57 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 14:46:57 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 14:46:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:46:59 INFO - Downloading/unpacking functools32 (from jsonschema) 14:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:59 INFO - Downloading functools32-3.2.3-2.tar.gz 14:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:46:59 INFO - warning: no files found matching '*.txt' 14:46:59 INFO - no previously-included directories found matching 'build' 14:46:59 INFO - no previously-included directories found matching 'dist' 14:46:59 INFO - no previously-included directories found matching '.git*' 14:46:59 INFO - Installing collected packages: jsonschema, functools32 14:46:59 INFO - Running setup.py install for functools32 14:47:00 INFO - warning: no files found matching '*.txt' 14:47:00 INFO - no previously-included directories found matching 'build' 14:47:00 INFO - no previously-included directories found matching 'dist' 14:47:00 INFO - no previously-included directories found matching '.git*' 14:47:00 INFO - Successfully installed jsonschema functools32 14:47:00 INFO - Cleaning up... 14:47:00 INFO - Return code: 0 14:47:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:47:00 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')]} 14:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:00 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')]} 14:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:47:00 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 0x1067dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d84b3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, '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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 14:47:00 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 14:47:00 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 14:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:00 INFO - 'HOME': '/Users/cltbld', 14:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:00 INFO - 'LOGNAME': 'cltbld', 14:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:00 INFO - 'MOZ_NO_REMOTE': '1', 14:47:00 INFO - 'NO_EM_RESTART': '1', 14:47:00 INFO - 'PAGER': '/bin/cat', 14:47:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:00 INFO - 'PWD': '/builds/slave/test', 14:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:00 INFO - 'SHELL': '/bin/bash', 14:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:47:00 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:00 INFO - 'USER': 'cltbld', 14:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:00 INFO - 'XPC_FLAGS': '0x0', 14:47:00 INFO - 'XPC_SERVICE_NAME': '0', 14:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:00 INFO - Downloading/unpacking flake8 14:47:00 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 14:47:00 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 14:47:00 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 14:47:00 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 14:47:00 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 14:47:00 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 14:47:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:47:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:47:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:47:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:47:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:47:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:47:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:47:04 INFO - no previously-included directories found matching 'docs/_build' 14:47:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:47:04 INFO - Running setup.py install for pep8 14:47:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:47:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:47:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:47:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:47:04 INFO - no previously-included directories found matching 'docs/_build' 14:47:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:47:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:47:04 INFO - Cleaning up... 14:47:04 INFO - Return code: 0 14:47:04 INFO - Running post-action listener: _resource_record_post_action 14:47:04 INFO - Running post-action listener: _start_resource_monitoring 14:47:04 INFO - Starting resource monitoring. 14:47:04 INFO - ##### 14:47:04 INFO - ##### Running install step. 14:47:04 INFO - ##### 14:47:04 INFO - Running pre-action listener: _resource_record_pre_action 14:47:04 INFO - Running main action method: install 14:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:47:04 INFO - Reading from file tmpfile_stdout 14:47:04 INFO - Detecting whether we're running mozinstall >=1.0... 14:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:47:04 INFO - Reading from file tmpfile_stdout 14:47:04 INFO - Output received: 14:47:04 INFO - Usage: mozinstall [options] installer 14:47:04 INFO - Options: 14:47:04 INFO - -h, --help show this help message and exit 14:47:04 INFO - -d DEST, --destination=DEST 14:47:04 INFO - Directory to install application into. [default: 14:47:04 INFO - "/builds/slave/test"] 14:47:04 INFO - --app=APP Application being installed. [default: firefox] 14:47:04 INFO - mkdir: /builds/slave/test/build/application 14:47:04 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'] 14:47:04 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 14:47:39 INFO - Reading from file tmpfile_stdout 14:47:39 INFO - Output received: 14:47:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:47:39 INFO - Running post-action listener: _resource_record_post_action 14:47:39 INFO - ##### 14:47:39 INFO - ##### Running run-tests step. 14:47:39 INFO - ##### 14:47:39 INFO - Running pre-action listener: _resource_record_pre_action 14:47:39 INFO - Running main action method: run_tests 14:47:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:47:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:47:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:47:39 INFO - 2016-02-26 14:47:39.269 screenresolution[2318:17939] starting screenresolution argv=screenresolution get 14:47:39 INFO - 2016-02-26 14:47:39.272 screenresolution[2318:17939] Display 0: 1600x1200x32@60 14:47:39 INFO - 2016-02-26 14:47:39.278 screenresolution[2319:17941] starting screenresolution argv=screenresolution list 14:47:39 INFO - Available Modes on Display 0 14:47:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:47:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:47:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:47:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:47:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:47:39 INFO - Intel Iris: 14:47:39 INFO - Chipset Model: Intel Iris 14:47:39 INFO - Type: GPU 14:47:39 INFO - Bus: Built-In 14:47:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:47:39 INFO - Vendor: Intel (0x8086) 14:47:39 INFO - Device ID: 0x0a2e 14:47:39 INFO - Revision ID: 0x0009 14:47:39 INFO - Displays: 14:47:39 INFO - Display: 14:47:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:47:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:47:39 INFO - Display Serial Number: 1600x1200 60 14:47:39 INFO - Main Display: Yes 14:47:39 INFO - Mirror: Off 14:47:39 INFO - Online: Yes 14:47:39 INFO - Rotation: Supported 14:47:39 INFO - Return code: 0 14:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:47:39 INFO - Python 2.7.3 14:47:39 INFO - Return code: 0 14:47:39 INFO - grabbing minidump binary from tooltool 14:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:47:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106680d30>, '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 0x1064a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106677b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:47:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:47:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:47:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:47:39 INFO - Return code: 0 14:47:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:47:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:47:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:47:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:47:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:47:40 INFO - Return code: 0 14:47:40 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/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 14:47:40 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/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --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 14:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1AOG8k9zHr/Render', 14:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:40 INFO - 'HOME': '/Users/cltbld', 14:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:40 INFO - 'LOGNAME': 'cltbld', 14:47:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:47:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:40 INFO - 'MOZ_NO_REMOTE': '1', 14:47:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:47:40 INFO - 'NO_EM_RESTART': '1', 14:47:40 INFO - 'PAGER': '/bin/cat', 14:47:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:40 INFO - 'PWD': '/builds/slave/test', 14:47:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:40 INFO - 'SHELL': '/bin/bash', 14:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTuAO9Y3KJ/Listeners', 14:47:40 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 14:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:40 INFO - 'USER': 'cltbld', 14:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:40 INFO - 'XPC_FLAGS': '0x0', 14:47:40 INFO - 'XPC_SERVICE_NAME': '0', 14:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:40 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/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--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 14:47:41 INFO - mozversion application_buildid: 20160226124627 14:47:41 INFO - mozversion application_changeset: b5c0cd56381547fe527c724d86eb955c209e0a6e 14:47:41 INFO - mozversion application_display_name: Nightly 14:47:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:47:41 INFO - mozversion application_name: Firefox 14:47:41 INFO - mozversion application_remotingname: firefox 14:47:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:47:41 INFO - mozversion application_vendor: Mozilla 14:47:41 INFO - mozversion application_version: 47.0a1 14:47:41 INFO - mozversion platform_buildid: 20160226124627 14:47:41 INFO - mozversion platform_changeset: b5c0cd56381547fe527c724d86eb955c209e0a6e 14:47:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:47:41 INFO - mozversion platform_version: 47.0a1 14:47:41 INFO - using testdate: 1456526861 14:47:41 INFO - actual date: 1456526861 14:47:41 INFO - starting webserver on 'localhost:50001' 14:47:41 INFO - SUITE-START | Running 2 tests 14:47:41 INFO - TEST-START | dromaeo_css 14:47:41 INFO - operating with platform_type : mac_ 14:47:41 INFO - Initialising browser for dromaeo_css test... 14:47:41 INFO - TEST-INFO | started process 2337 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpSGHOZz/profile http://localhost:50001/getInfo.html) 14:47:43 INFO - PROCESS | 2337 | __metrics Screen width/height:1600/1200 14:47:43 INFO - PROCESS | 2337 | colorDepth:24 14:47:43 INFO - PROCESS | 2337 | Browser inner width/height: 1024/689 14:47:43 INFO - PROCESS | 2337 | __metrics 14:47:43 INFO - PROCESS | 2337 | 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" 14:47:43 INFO - PROCESS | 2337 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:47:43 INFO - TEST-INFO | 2337: exit 0 14:47:43 INFO - Browser initialized. 14:47:43 INFO - Running cycle 1/1 for dromaeo_css test... 14:47:43 INFO - TEST-INFO | started process 2340 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpSGHOZz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:55 INFO - PROCESS | 2340 | RSS: Main: 244498432 14:47:55 INFO - PROCESS | 2340 | 14:48:46 INFO - PROCESS | 2340 | 1456526926519 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] 14:50:57 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50001/tests/dromaeo/cssquery-ext.html) 14:50:58 INFO - PROCESS | 2340 | RSS: Main: 356229120 14:50:58 INFO - PROCESS | 2340 | 14:54:00 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-ext.html (next: http://localhost:50001/tests/dromaeo/cssquery-jquery.html) 14:54:01 INFO - PROCESS | 2340 | RSS: Main: 376057856 14:54:01 INFO - PROCESS | 2340 | 14:57:03 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50001/tests/dromaeo/cssquery-mootools.html) 14:57:04 INFO - PROCESS | 2340 | RSS: Main: 377774080 14:57:04 INFO - PROCESS | 2340 | 15:00:06 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50001/tests/dromaeo/cssquery-prototype.html) 15:00:06 INFO - PROCESS | 2340 | RSS: Main: 333586432 15:00:06 INFO - PROCESS | 2340 | 15:03:09 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50001/tests/dromaeo/cssquery-yui.html) 15:03:10 INFO - PROCESS | 2340 | RSS: Main: 334901248 15:03:10 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | Cycle 1(1): loaded http://localhost:50001/tests/dromaeo/cssquery-yui.html (next: http://localhost:50001/tests/dromaeo/cssquery-dojo.html) 15:06:13 INFO - PROCESS | 2340 | RSS: Main: 438337536 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | __start_tp_report 15:06:13 INFO - PROCESS | 2340 | _x_x_mozilla_page_load 15:06:13 INFO - PROCESS | 2340 | _x_x_mozilla_page_load_details 15:06:13 INFO - PROCESS | 2340 | |i|pagename|runs| 15:06:13 INFO - PROCESS | 2340 | |0;dojo.html;3396.337714145741;3419.504171895075;3429.7942123472576;3441.7590768646187;3479.895603131907;5558.221848941152;5587.329520457546;5621.971890140554;5631.352394474631;5659.745311460983;4623.445186577607;5619.129034999572;5646.491815736583;5710.600257981943;5743.4543718346695;5015;5052.989402119581;5084.77118529667;5086.643934924557;5247.1866860636665;5166.9224961625605;5187.429382767892;5200.401953775307;5229.294045303874;5237.973810130944;5054.267131265964;5088.313077734496;5106.821261255838;5153.3558305211845;5156.948430515685;5119.488051194888;5121.00140472608;5123.846284611468;5142.845714628567;5182.300389447414;5055.974720126413;5060.202725487463;5082.822101226477;5103.693778373309;5110.233464980245;5409.377921539034;5498.945010549924;5514.751836167382;5553.389127196006;5556.666600004012;5633.774649014034;5826.572489739999;6151.569390142692;6155.045967874987;6178.042403427476;6205.913965056097;6224.470919971808;6235.407636274546;6242.313345531988;6268.968655156741;5589.8323050308145;5675.5459563234845;5682.204491371212;5682.829515114553;5689.516391106719;6187.257529096537;6196.938030619636;6205.937940620626;6211;6221.875562488724;6013.909791353133;6055.273367195965;6057.575969682168;6116.3577824328695;6144.262688477322;6109.1010297800785;6111.297200821962;6154.938450615526;6213.720382582795;6251.031090180938;6135.477655820289;6138.048602466625;6142.679323945373;6149.7847575334645;6151.7539298428455;6122.3778139773995;6145.985912324506;6158.322584515699;6163.784267550704;6170.10533472653;4162.666986641061;4255.76593287366;4676.485586585541;4812.422509298906;4820.469748327681;4320.114376552862;4398.626116780109;4407.162639098625;4417.845375411845;4425.756583387944;5649.802256920988;5671.602987790898;5708.20085188074;5771.9711401443565;5776.104703770839;6225.346338634378;6237.345078766755;6242.65665388408;6260.311365749764;6261.686915654199;5625.409332020161;5695.886082278331;5732.054211055212;5738.770449181986;5760.51035661965;5166.147585648401;5280.709560974106;5280.7359632019015;5289.259503669489;5297.231901374279;6203.658798766111;6223.284322302902;6223.906641400383;6242.687865606701;6260.812175634738;6121.47967422774;6136.610985192662;6154.046122850965;6200.441960223601;6203.2245969253845;6198.907016394667;6200.038993955944;6202.56582039271;6214.751409943732;6221.035739460391;5970.432808883149;6127.172831667668;6129.632222066691;6150.538709596709;6159.1069294951085;4748.952510474851;5848.6783226921225;6118.588722523033;6135.233095863017;6144.463884028993;5805.506531944832;5919.792807251725;5960.374160713236;5965.164876917148;5975.34271230173;5942.168096466585;5952.404759524013;5957.791477298274;5961.642301461926;6001.789937352171;5388.515033646991;5711;5804;5809.331926828299;5812.389699081536;6031.909521357095;6035.607685500604;6036.366181550874;6054.182685337423;6103.786367477117;5785.305763308262;5799.536037117105;5821.825345239649;5830.970845145747;5862.062070068769 15:06:13 INFO - PROCESS | 2340 | |1;ext.html;482.6356101143636;495.50697056428845;565.8287345195447;584.4914924016106;605.122572270208;36418.817905910466;37067;37105.44341834875;37114.886553403405;37230.999999999935;38751;39121.413178802344;39147.60852391482;39241.41137882934;39254.41118383219;9435.716928492138;9660.75848103796;9668.806623867518;9670.274729395289;9683.515824208796;9453.33826632136;9624.903750962505;9625.085616866421;9630.614775408976;9636.421814691106;9610.855837162448;9644.807103857918;9685.75785605362;9704.029597040275;9717.659881904136;9616.663416780415;9665.420074795464;9675.516224188763;9677.516124193762;9681.322307438517;9561.187299079587;9654.03459654036;9655.758606034906;9659.613615455373;9673.70978870635;38229.42655860164;38523.8073809632;38571.22857542861;38588.614113858785;38594;3075.492543730282;3152.4325621388143;3259.41330560499;3326.933461330784;3480.216951185963;27229.04698335549;27697.446051079067;27925.16224513268;28089.297767555974;28314.43371132566;2698.055680511825;2747.8900843966217;2760.641116654822;2793.5530315149476;2833.4474777418413;376265;378449;379099;379224;380052;43157;59541.70229148826;59859;60044.699776501715;60124.39875601276;41970;42019.78990105091;42059;42133.469312256646;42229.57770422318;15028.549143525712;15313.693726125415;15333.386664533517;15340.463083792238;15351.15568643713;12341.75316493665;12384.504619815287;12425.943794777364;12435.564755233523;12470;13679.042467027411;13853.930730346283;13991.370388332549;13995.95030372726;14097.321508913274;6230.501559875199;6261.78083767066;6288.685565721696;6294.181756371642;6300.779472718433;850.927671147947;914.7072936660205;915.4507295622573;977.4233202410612;1001.6594357918198;27905.581416278772;28077.578836317472;28297;28557.857210713813;28735;1079.6005477973201;1084.8372744088292;1105.2594761509808;1146.9025132863633;1158.2760774515928;14353.85646143546;14364.569062928129;14638.902082343653;14648.267586620626;14712.970092093665;497609;498288;499419.0290485461;499840;503304;54400;54743.45256547463;54752;55054.17418738722;55143.72428137834;1280.5582074184579;1395.9092658977133;1421.8862491000696;1431.2485944879022;1442.776369662704;27274.318142046606;27835.304117397227;28347.85826070939;28900.27749306284;29047;44134.55865441304;44848.32727509025;45315.32027019663;45362;45526.54473455356;359836;361295;362014;362082;362095;27413.17760467189;31317.8434107828;31769.682303176673;32145;32222.033338999867;39270.01824954399;39763;40030.59969400269;40083.39874901937;40490.9877253071;32897.671023290415;33361.66553337839;33419.83290083534;33858.32283354369;34205.828970854986;32877.50683739794;32931;33202.833985829915;33224.501632476014;33759.81840635566;34512.65487345094;34725;35535;35548.64451355454;35682.46476302909;30151.849240753654;30980.84509577528;31048.844755776077;31126.844365778932;31547.842260788548;19174.904125479283;23166.42083947915;23346.883265583565;23646.527069458858;23972.88013559921 15:06:13 INFO - PROCESS | 2340 | |2;jquery.html;310.7234561240496;350.77199820116937;353.68698701649043;355.1635897461478;358.9013021419111;19206.519837004063;19234.942078185693;21386.25148119816;21462.819544925063;21607;20626.1749530019;20936.057877395517;20945.05747241374;21212.469688257803;21253.25613603524;5803.375054984601;6073.362296958822;6148.784792532262;6162.568620196588;6163.383661633846;4246.2993606055115;4306.827726890936;5986.850328741794;6280.214973128359;6556.770513032045;6469.417752402306;6481.967590162042;6498.935010649904;6505.772297969572;6511.772087976922;6318.715657795387;6631.502637302174;6642.103316052319;6646.468282537409;6652.334766523358;3726.6087060858763;5372.435894231127;5601.243832082658;5617.634853734535;5718.771249150029;24729.887155078064;24966.625500617505;25136.748632513623;25501;25794.742052579422;2131.8507704460694;2149.3874245840016;2210.204326442479;2218.267971569378;2238.1383167480635;14550.99145926535;14618.70762584753;14778.26108694561;14939;15055.698886022328;1682.3691115831564;1730.2732852202105;1796.7843858736906;1814.2198854492563;1873.4379686094253;93146;102619.48690256575;105317;106454;107171;10774.32997885329;11122.054625356774;11279.210455268216;11303.604373846876;11421.486033128529;13402.731945361038;13495.460181592824;13509.459621615222;13531.864681353258;13534.661633459242;8427.115152908857;8568.785780355416;8599.828003439896;8668.956655216683;8685.305175585938;8730.301575873915;8763.56182190888;8779.165979231962;8863.113688631085;8865.778355541162;3779.9244015120094;3839.500864887547;4443.555644435531;4479.216137176047;4509.842155524541;1733.2633630706898;1779.2349289805509;1821.3807305516189;1831.954201144982;1846.7783865935905;871.4988881393224;911.562450023979;925.4909799610322;959.7024922274118;1014.7158795537262;18653.533661658297;18977.33579324717;19735.724209621454;20218.393448196577;20950.685739713917;872.7425409504084;928.5218112671979;928.5218112671979;951.6811868024289;970.9223262139012;8948.642054317885;11014.377985102425;11064.17018723599;11125.443727813577;11143.832842507369;574617;577433;582665;586651.8442053379;594630;95808.56287155698;116647;117474;117729;117897;971.3249291742312;971.4220039076738;986.6201512417628;987.2842189412762;987.4026214140486;17978.5505362367;17980;17995.2801887923;18073.096345182952;18409.81590184081;31125.22186945254;50064.749676251384;50398.19624350666;51324.48675513197;51843;63421.682891585246;65984.35456358391;66843.33156668565;67281.9538534602;74734;7878.566678832719;8019.599020048858;8207.384446166441;8301.83396332082;8371.246587807127;9321.906780932375;12379.876201237872;13240.20558766477;13597.456101756216;13775.724485510033;11027.53900031842;11330.886691133313;11385.772284554097;11403.657890263305;11894.167408281337;11186.83219751688;11209.159313051718;11673.94934455903;11716.765664686489;11815.527378904748;11515.942420287845;11634.301941883514;11728.296302221894;11941.044716422839;12498.875011249771;7990;8072.152423995631;8135.552544609866;8414.83170336602;8448.91551084506;11345.978861902468;11462.484188211718;11511.712207194887;11742.471588778355;11924.642260732191 15:06:13 INFO - PROCESS | 2340 | |3;mootools.html;1366.6310096274012;1507.223779753427;1532.3487517804933;1763.3387479695114;1954.3550628292664;7221.458390620698;7939.8809017864705;8441.831163376728;8609;8625.956870215648;8335.499870007789;8612.827743445127;8618.439801412904;8619.353548483874;8620.396572239946;2190.6823510590953;2423.72127205371;2473.54239465699;2503.4367267364787;2508.7867531259867;2490.8007359411204;2504.8246622736415;2508;2510.3598582361474;2517.937051573707;2397.496525729599;2416.8187385946035;2416.8791560421996;2417.6615273861676;2422.4186195313087;2379.6430535419654;2397.184957114572;2438.2197696737053;2495.9500809983697;2497.3881399057345;2479.4049428137296;2490.0786708917635;2492.9252122436174;2504.0860086068637;2516.043903316728;8730.301575873978;8796.384253102286;8813.603387847563;8818.206361427438;8834.160754728355;5201.453847346012;5374.758135883894;5408.296921400232;5418.106012507931;5418.566514678856;3267.9183020424443;3529.2764983178386;3584.6557540922154;3725.9068523286865;3726.1988672435286;1052.4895425718596;1069.315637991686;1079.881213066562;1088.472091035847;1117.4468638024148;11588.884111158864;11797.174197806156;11829.585964491287;11843.88156118445;11846.703832404173;4839.879003024953;4920.901581968412;4985.4266759323145;5009.5741861941415;5024.598032157419;10110.24173187014;10124.088832005007;10139.746506337253;10155.543000564992;10175.135113515285;5490.945090549043;5497.642653227527;5513.117901135799;5542.501174894279;5542.61201715876;4365.476142862877;4494.258447356149;4498.100379924028;4531.864044078683;4727.669063165546;3670.4861319415304;3689.815509224528;3956.1098752780968;3994.1013272013565;4035;1286.8327117474669;1300.5058077930325;1304.4912484131196;1316.5457917018614;1325.5294370498334;1100.7248187953012;1116.8715597706205;1123.095907794217;1273.8853503184757;1372.293268966483;8010.559419231999;8074;8124.390670699721;8130.75607731769;8303.626336814857;1097.3635291530893;1117.80997230471;1177.7526719389025;1178.6287319494504;1204.8253003314646;6194.38056194377;6213.285472170668;6285.308616052231;6286.874262514815;6290.4653104485715;10139.64511242103;10458.52936617854;10579.471026448648;10579.682609521727;10590.940905909501;10257.974202579682;10281.074703276741;10323.019313165387;10431.739206519898;10551.630692925864;7690.692372305045;8305.916940830635;8330.416870819106;8334.374921880883;8352.997640283083;7784.377249820114;7859.646315915683;7907.446478746662;7923.405744569065;7953.204679532;9295.860562091439;9426.528673566432;9511.714648560554;9574.712758617252;9682.612695492102;9540.284478664267;9940.45327506965;9989.500524973577;10002.699919002442;10019.098281154731;6363.904541431974;6530.869382612226;6921.82695432598;6980.755673551402;7084.822879428056;8135.9186408135165;8181.6318265677;8210.3021243195;8222.671093156207;8228.753137405887;7213.422926165999;7262.164851041986;7286.817829554304;7395.445341599294;7405.62971851416;7157.64211789398;7203;7229.313215244543;7232.559996805486;7248.238934911969;7579.052618422697;7646.311831935152;7661.425393095428;7662.846743064996;7688.538687678757;6945.756898508528;7047.823804404931;7050.964745176241;7054.435645148478;7055.153381594241;6815.250322464624;7064.858702826017;7078.398336141279;7119.822004449723;7169.390601798906 15:06:13 INFO - PROCESS | 2340 | |4;prototype.html;316.8194129346273;318.87882604610246;328.56957385824586;329.42844165373066;332.26071989822634;6579;6646.634435106073;6653.700583473743;6664.733410663568;6665.40011398974;6492.091107244978;6532.020196970457;6546.639934803584;6554.639494827783;6565.277819439858;4565.817367305303;4643.744594047326;4707.576318131368;4830.444498882621;4878.951210487903;4827.758612069382;4831.782569784351;4847.709137451764;4859.246816743411;4863.294822250771;4772.80908763649;4777.713337199779;4787.784549695272;4794.400699912511;4802.351682522867;4580.152671755719;4666.346711460398;4752.904941901143;4774.212254977924;4783.617310615143;4051.554329023805;4070.409790580349;4076.103257283393;4102.384642303648;4106.691998100155;6208.099825525273;6208.6585237811905;6282.403171698681;6287.685615719196;6326.323524266073;5641.238432811559;5657.886842263173;5668.489835914787;5686.943130568683;5694.800681976111;5621.747021384048;6938.826529336757;6939.271376505445;6944.4097251733665;6960.199577048654;4294.742315461082;4469.150861336336;4470.061287129706;4482.618977386926;4482.8655140345845;7220.169680486706;7357.595332256724;7360.55836649808;7367.336939675455;7375.926240737577;4574.428196475441;4586.334981427741;4591.081783643284;4592.7933243004145;4594.241950078266;4647.674662773641;4649.674522783373;4665.370175026396;4668.043051174501;4670.906581868411;5545.195946587723;5553.861153471195;5554.583406244547;5561.721913904369;5582.0789569721355;6662.200535935719;6972.825679357955;6994.33553812387;7039.54242974205;7040.401565866959;1604.8074231092344;1607.469535053453;1616.7574863770528;1618.6358069434518;1621.805383354005;815.7471183933;1000.9299349045642;1020.6070662794878;1027.8972102789662;1050.1231471721046;6448.645324507197;6528.706208220641;6566.015097735282;6566.376194261537;6570.178727659043;7183.604901730455;7204.279572042754;7207.675654595556;7211.89182162268;7225.891611625725;5406.54044406222;6523.739050438025;6523.7716679915975;6525.836854078591;6527.608343499405;3760.2667479841716;3813.637704418076;3818.0645741793437;3821.732478726518;3827.3110840048503;7313.707451701979;7586.165521792598;7595.354394876387;7599.164091949881;7629.2752188542;7450;7532.849343013109;7579.355754760907;7579.583122928292;7596.2023987481625;7629.0845098588525;7643.464957452925;7645.5794931279315;7653.9234607654325;7660.80847978805;7027.789166325018;7307.1231452226075;7325.963370183115;7328.413726901942;7359.300866417681;7154.427645788429;7216.675249613675;7222.09723784527;7222.783316500513;7229.891551626835;7612.352949999312;7625.932369468168;7636.656350464132;7646.197149299466;7660.923390766244;4757.500462451393;4796.37647105874;4800.6159507238635;4802.303665968345;4803.687760295569;7003.859922801414;7158.212596614264;7208.71165153388;7209.747658831916;7211.71153153868;6964.129483814523;7004.268335349116;7015.824604384931;7020.613866237406;7021.648917554;6980.790576282517;7000.789976300719;7006.789796306119;7026.402755765613;7043.436525078084;6821.454283657395;6826.829329266808;6841.350071743176;6852.177738671391;6854.897176542256;6759.188897332351;7021.719131234693;7032.648367581498;7044.978478120746;7065.01089847432;6546.705398256994;6683.264840867598;6693.765718199839;6723.4957378197805;6872.62200578953 15:06:13 INFO - PROCESS | 2340 | |5;yui.html;643.7875501084636;655.4657953767679;666.167290886392;719.5071376107367;750.5909279442704;14237.572872813818;14427.134371937665;14450;14457.783133252997;14479.203643799587;12143;12926.353682315894;12944.352782360891;12973.897218736418;12981.545645902372;858.6694122762734;863.3999370437548;872.2455076358935;872.3849685971375;877.863931090682;861.418542483824;866.9826603467927;871.4509858788986;873.3449912565576;874.9825003499926;918.9678361257357;921.3642586615248;921.9535826836521;924.5192499900081;924.6162842420388;908.9045650206732;909.6634245329204;911.0388540090169;912.3613470570594;912.5254867468946;676.2696288008939;682.7849227493324;684.6576711644178;687.298955065835;688.4939569416475;12371.13402061856;12411.689707757378;12414.56549020789;12414.875851241462;12426.316552589604;11132.777344453147;11354.943225283903;11359.204855660108;11443.656690299304;11479.368634725086;9475.336726429152;9679.419234845931;9813.754656133582;9946.651867184613;9954.004599539989;3421.4183588789965;3429.248122919531;3467.4452087666104;3486.041338631871;3559.626239244868;75336.62331688362;76349.61825190838;76734.23265767327;76836.23163768346;79044.60477697689;8252.33981281496;8366.414350995494;8387.287080598095;8389.286910612544;8473.406861519758;19063.90468047651;19075.950822704883;19089.522761931064;19099.809001910082;19122.04389780533;7387.556746595222;7388.187299397062;7434.256574342522;7801.258880406353;7830.6084695764985;4087.8773636790943;4120.75275483472;4170.666346692257;4179.728317659343;4213.410122582841;3523.0487768302414;3578.051816268691;3578.3916734155255;3582.0865679251624;3585.2650206707563;498.9501049895054;638.8594509207967;649.7530938243438;659.8779225843234;691.4322737636619;481.32614339923686;523.8088097844336;536.6458137629146;565.5390856451978;598.4554055809193;8030.879536806952;8077.596120193967;8322.16778322175;8394.790130246793;8407.2433480987;472.6384315998264;518.157993260951;533.6211289984154;573.6959411511905;596.397638385234;3388.847501862422;3470.704990075872;3908.765474071508;3933.3706606943324;4065.8780236592356;82851;83895.58052209821;83933.74099388513;84182.31635367381;91746.62380064305;79890;80511.7923231152;81517;81914.59042704749;91601.54199228961;70893;73031;73614.26385736182;73833;76600.5739368669;8396.32829373661;8472;8627.91372086271;8681.956590217262;8757.824843503222;50083;51673.48326516687;51689.741551293504;52709;55001.72499137479;71208.64395678196;71704.28295716977;71707.64146179236;71717.28282717106;71930.64034679793;9810.901890980998;10765.407902565434;10831.404272764757;10974.012338889539;11642.068634509389;9207.283851264026;10609.840852387375;10625.25623206368;10953.83569246478;11086.168537359892;10928.836067458835;11400.600978965696;11725.944664979852;11866.110041747077;12366.690832729253;11209.49557269941;11237.662870113909;11591.304521728724;11608.941955290169;11649.767004660029;11229.27009744364;11441.771164576829;11446.599369022044;11562.76874462523;12214.450349734461;8894.688685895962;9127.589258483516;9238.30712696564;9298.395604285699;9549.618015279311;8911.866322005304;9163.679271225476;9447.622095116118;9677.467515340168;9876.50617469138 15:06:13 INFO - PROCESS | 2340 | __end_tp_report 15:06:13 INFO - PROCESS | 2340 | __start_cc_report 15:06:13 INFO - PROCESS | 2340 | _x_x_mozilla_cycle_collect,1023 15:06:13 INFO - PROCESS | 2340 | __end_cc_report 15:06:13 INFO - PROCESS | 2340 | __startTimestamp1456527973502__endTimestamp 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | ------- Summary: start ------- 15:06:13 INFO - PROCESS | 2340 | Number of tests: 6 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#0] dojo.html Cycles:165 Average:5640.50 Median:5805.51 stddev:642.52 (11.1%) stddev-sans-first:619.90 15:06:13 INFO - PROCESS | 2340 | Values: 3396.3 3419.5 3429.8 3441.8 3479.9 5558.2 5587.3 5622.0 5631.4 5659.7 4623.4 5619.1 5646.5 5710.6 5743.5 5015.0 5053.0 5084.8 5086.6 5247.2 5166.9 5187.4 5200.4 5229.3 5238.0 5054.3 5088.3 5106.8 5153.4 5156.9 5119.5 5121.0 5123.8 5142.8 5182.3 5056.0 5060.2 5082.8 5103.7 5110.2 5409.4 5498.9 5514.8 5553.4 5556.7 5633.8 5826.6 6151.6 6155.0 6178.0 6205.9 6224.5 6235.4 6242.3 6269.0 5589.8 5675.5 5682.2 5682.8 5689.5 6187.3 6196.9 6205.9 6211.0 6221.9 6013.9 6055.3 6057.6 6116.4 6144.3 6109.1 6111.3 6154.9 6213.7 6251.0 6135.5 6138.0 6142.7 6149.8 6151.8 6122.4 6146.0 6158.3 6163.8 6170.1 4162.7 4255.8 4676.5 4812.4 4820.5 4320.1 4398.6 4407.2 4417.8 4425.8 5649.8 5671.6 5708.2 5772.0 5776.1 6225.3 6237.3 6242.7 6260.3 6261.7 5625.4 5695.9 5732.1 5738.8 5760.5 5166.1 5280.7 5280.7 5289.3 5297.2 6203.7 6223.3 6223.9 6242.7 6260.8 6121.5 6136.6 6154.0 6200.4 6203.2 6198.9 6200.0 6202.6 6214.8 6221.0 5970.4 6127.2 6129.6 6150.5 6159.1 4749.0 5848.7 6118.6 6135.2 6144.5 5805.5 5919.8 5960.4 5965.2 5975.3 5942.2 5952.4 5957.8 5961.6 6001.8 5388.5 5711.0 5804.0 5809.3 5812.4 6031.9 6035.6 6036.4 6054.2 6103.8 5785.3 5799.5 5821.8 5831.0 5862.1 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#1] ext.html Cycles:180 Average:55122.64 Median:199256.91 stddev:110855.53 (55.6%) stddev-sans-first:111090.60 15:06:13 INFO - PROCESS | 2340 | Values: 482.6 495.5 565.8 584.5 605.1 36418.8 37067.0 37105.4 37114.9 37231.0 38751.0 39121.4 39147.6 39241.4 39254.4 9435.7 9660.8 9668.8 9670.3 9683.5 9453.3 9624.9 9625.1 9630.6 9636.4 9610.9 9644.8 9685.8 9704.0 9717.7 9616.7 9665.4 9675.5 9677.5 9681.3 9561.2 9654.0 9655.8 9659.6 9673.7 38229.4 38523.8 38571.2 38588.6 38594.0 3075.5 3152.4 3259.4 3326.9 3480.2 27229.0 27697.4 27925.2 28089.3 28314.4 2698.1 2747.9 2760.6 2793.6 2833.4 376265.0 378449.0 379099.0 379224.0 380052.0 43157.0 59541.7 59859.0 60044.7 60124.4 41970.0 42019.8 42059.0 42133.5 42229.6 15028.5 15313.7 15333.4 15340.5 15351.2 12341.8 12384.5 12425.9 12435.6 12470.0 13679.0 13853.9 13991.4 13996.0 14097.3 6230.5 6261.8 6288.7 6294.2 6300.8 850.9 914.7 915.5 977.4 1001.7 27905.6 28077.6 28297.0 28557.9 28735.0 1079.6 1084.8 1105.3 1146.9 1158.3 14353.9 14364.6 14638.9 14648.3 14713.0 497609.0 498288.0 499419.0 499840.0 503304.0 54400.0 54743.5 54752.0 55054.2 55143.7 1280.6 1395.9 1421.9 1431.2 1442.8 27274.3 27835.3 28347.9 28900.3 29047.0 44134.6 44848.3 45315.3 45362.0 45526.5 359836.0 361295.0 362014.0 362082.0 362095.0 27413.2 31317.8 31769.7 32145.0 32222.0 39270.0 39763.0 40030.6 40083.4 40491.0 32897.7 33361.7 33419.8 33858.3 34205.8 32877.5 32931.0 33202.8 33224.5 33759.8 34512.7 34725.0 35535.0 35548.6 35682.5 30151.8 30980.8 31048.8 31126.8 31547.8 19174.9 23166.4 23346.9 23646.5 23972.9 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#2] jquery.html Cycles:180 Average:33449.59 Median:25647.87 stddev:96632.73 (376.8%) stddev-sans-first:96871.77 15:06:13 INFO - PROCESS | 2340 | Values: 310.7 350.8 353.7 355.2 358.9 19206.5 19234.9 21386.3 21462.8 21607.0 20626.2 20936.1 20945.1 21212.5 21253.3 5803.4 6073.4 6148.8 6162.6 6163.4 4246.3 4306.8 5986.9 6280.2 6556.8 6469.4 6482.0 6498.9 6505.8 6511.8 6318.7 6631.5 6642.1 6646.5 6652.3 3726.6 5372.4 5601.2 5617.6 5718.8 24729.9 24966.6 25136.7 25501.0 25794.7 2131.9 2149.4 2210.2 2218.3 2238.1 14551.0 14618.7 14778.3 14939.0 15055.7 1682.4 1730.3 1796.8 1814.2 1873.4 93146.0 102619.5 105317.0 106454.0 107171.0 10774.3 11122.1 11279.2 11303.6 11421.5 13402.7 13495.5 13509.5 13531.9 13534.7 8427.1 8568.8 8599.8 8669.0 8685.3 8730.3 8763.6 8779.2 8863.1 8865.8 3779.9 3839.5 4443.6 4479.2 4509.8 1733.3 1779.2 1821.4 1832.0 1846.8 871.5 911.6 925.5 959.7 1014.7 18653.5 18977.3 19735.7 20218.4 20950.7 872.7 928.5 928.5 951.7 970.9 8948.6 11014.4 11064.2 11125.4 11143.8 574617.0 577433.0 582665.0 586651.8 594630.0 95808.6 116647.0 117474.0 117729.0 117897.0 971.3 971.4 986.6 987.3 987.4 17978.6 17980.0 17995.3 18073.1 18409.8 31125.2 50064.7 50398.2 51324.5 51843.0 63421.7 65984.4 66843.3 67282.0 74734.0 7878.6 8019.6 8207.4 8301.8 8371.2 9321.9 12379.9 13240.2 13597.5 13775.7 11027.5 11330.9 11385.8 11403.7 11894.2 11186.8 11209.2 11673.9 11716.8 11815.5 11515.9 11634.3 11728.3 11941.0 12498.9 7990.0 8072.2 8135.6 8414.8 8448.9 11346.0 11462.5 11511.7 11742.5 11924.6 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#3] mootools.html Cycles:180 Average:5995.14 Median:5017.09 stddev:3137.83 (62.5%) stddev-sans-first:3127.34 15:06:13 INFO - PROCESS | 2340 | Values: 1366.6 1507.2 1532.3 1763.3 1954.4 7221.5 7939.9 8441.8 8609.0 8626.0 8335.5 8612.8 8618.4 8619.4 8620.4 2190.7 2423.7 2473.5 2503.4 2508.8 2490.8 2504.8 2508.0 2510.4 2517.9 2397.5 2416.8 2416.9 2417.7 2422.4 2379.6 2397.2 2438.2 2496.0 2497.4 2479.4 2490.1 2492.9 2504.1 2516.0 8730.3 8796.4 8813.6 8818.2 8834.2 5201.5 5374.8 5408.3 5418.1 5418.6 3267.9 3529.3 3584.7 3725.9 3726.2 1052.5 1069.3 1079.9 1088.5 1117.4 11588.9 11797.2 11829.6 11843.9 11846.7 4839.9 4920.9 4985.4 5009.6 5024.6 10110.2 10124.1 10139.7 10155.5 10175.1 5490.9 5497.6 5513.1 5542.5 5542.6 4365.5 4494.3 4498.1 4531.9 4727.7 3670.5 3689.8 3956.1 3994.1 4035.0 1286.8 1300.5 1304.5 1316.5 1325.5 1100.7 1116.9 1123.1 1273.9 1372.3 8010.6 8074.0 8124.4 8130.8 8303.6 1097.4 1117.8 1177.8 1178.6 1204.8 6194.4 6213.3 6285.3 6286.9 6290.5 10139.6 10458.5 10579.5 10579.7 10590.9 10258.0 10281.1 10323.0 10431.7 10551.6 7690.7 8305.9 8330.4 8334.4 8353.0 7784.4 7859.6 7907.4 7923.4 7953.2 9295.9 9426.5 9511.7 9574.7 9682.6 9540.3 9940.5 9989.5 10002.7 10019.1 6363.9 6530.9 6921.8 6980.8 7084.8 8135.9 8181.6 8210.3 8222.7 8228.8 7213.4 7262.2 7286.8 7395.4 7405.6 7157.6 7203.0 7229.3 7232.6 7248.2 7579.1 7646.3 7661.4 7662.8 7688.5 6945.8 7047.8 7051.0 7054.4 7055.2 6815.3 7064.9 7078.4 7119.8 7169.4 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#4] prototype.html Cycles:180 Average:5738.73 Median:6550.67 stddev:1844.91 (28.2%) stddev-sans-first:1804.65 15:06:13 INFO - PROCESS | 2340 | Values: 316.8 318.9 328.6 329.4 332.3 6579.0 6646.6 6653.7 6664.7 6665.4 6492.1 6532.0 6546.6 6554.6 6565.3 4565.8 4643.7 4707.6 4830.4 4879.0 4827.8 4831.8 4847.7 4859.2 4863.3 4772.8 4777.7 4787.8 4794.4 4802.4 4580.2 4666.3 4752.9 4774.2 4783.6 4051.6 4070.4 4076.1 4102.4 4106.7 6208.1 6208.7 6282.4 6287.7 6326.3 5641.2 5657.9 5668.5 5686.9 5694.8 5621.7 6938.8 6939.3 6944.4 6960.2 4294.7 4469.2 4470.1 4482.6 4482.9 7220.2 7357.6 7360.6 7367.3 7375.9 4574.4 4586.3 4591.1 4592.8 4594.2 4647.7 4649.7 4665.4 4668.0 4670.9 5545.2 5553.9 5554.6 5561.7 5582.1 6662.2 6972.8 6994.3 7039.5 7040.4 1604.8 1607.5 1616.8 1618.6 1621.8 815.7 1000.9 1020.6 1027.9 1050.1 6448.6 6528.7 6566.0 6566.4 6570.2 7183.6 7204.3 7207.7 7211.9 7225.9 5406.5 6523.7 6523.8 6525.8 6527.6 3760.3 3813.6 3818.1 3821.7 3827.3 7313.7 7586.2 7595.4 7599.2 7629.3 7450.0 7532.8 7579.4 7579.6 7596.2 7629.1 7643.5 7645.6 7653.9 7660.8 7027.8 7307.1 7326.0 7328.4 7359.3 7154.4 7216.7 7222.1 7222.8 7229.9 7612.4 7625.9 7636.7 7646.2 7660.9 4757.5 4796.4 4800.6 4802.3 4803.7 7003.9 7158.2 7208.7 7209.7 7211.7 6964.1 7004.3 7015.8 7020.6 7021.6 6980.8 7000.8 7006.8 7026.4 7043.4 6821.5 6826.8 6841.4 6852.2 6854.9 6759.2 7021.7 7032.6 7045.0 7065.0 6546.7 6683.3 6693.8 6723.5 6872.6 15:06:13 INFO - PROCESS | 2340 | 15:06:13 INFO - PROCESS | 2340 | [#5] yui.html Cycles:180 Average:18099.71 Median:679.53 stddev:25816.56 (3799.2%) stddev-sans-first:25855.70 15:06:13 INFO - PROCESS | 2340 | Values: 643.8 655.5 666.2 719.5 750.6 14237.6 14427.1 14450.0 14457.8 14479.2 12143.0 12926.4 12944.4 12973.9 12981.5 858.7 863.4 872.2 872.4 877.9 861.4 867.0 871.5 873.3 875.0 919.0 921.4 922.0 924.5 924.6 908.9 909.7 911.0 912.4 912.5 676.3 682.8 684.7 687.3 688.5 12371.1 12411.7 12414.6 12414.9 12426.3 11132.8 11354.9 11359.2 11443.7 11479.4 9475.3 9679.4 9813.8 9946.7 9954.0 3421.4 3429.2 3467.4 3486.0 3559.6 75336.6 76349.6 76734.2 76836.2 79044.6 8252.3 8366.4 8387.3 8389.3 8473.4 19063.9 19076.0 19089.5 19099.8 19122.0 7387.6 7388.2 7434.3 7801.3 7830.6 4087.9 4120.8 4170.7 4179.7 4213.4 3523.0 3578.1 3578.4 3582.1 3585.3 499.0 638.9 649.8 659.9 691.4 481.3 523.8 536.6 565.5 598.5 8030.9 8077.6 8322.2 8394.8 8407.2 472.6 518.2 533.6 573.7 596.4 3388.8 3470.7 3908.8 3933.4 4065.9 82851.0 83895.6 83933.7 84182.3 91746.6 79890.0 80511.8 81517.0 81914.6 91601.5 70893.0 73031.0 73614.3 73833.0 76600.6 8396.3 8472.0 8627.9 8682.0 8757.8 50083.0 51673.5 51689.7 52709.0 55001.7 71208.6 71704.3 71707.6 71717.3 71930.6 9810.9 10765.4 10831.4 10974.0 11642.1 9207.3 10609.8 10625.3 10953.8 11086.2 10928.8 11400.6 11725.9 11866.1 12366.7 11209.5 11237.7 11591.3 11608.9 11649.8 11229.3 11441.8 11446.6 11562.8 12214.5 8894.7 9127.6 9238.3 9298.4 9549.6 8911.9 9163.7 9447.6 9677.5 9876.5 15:06:13 INFO - PROCESS | 2340 | -------- Summary: end -------- 15:06:13 INFO - PROCESS | 2340 | 15:06:14 INFO - TEST-INFO | 2340: exit 0 15:06:14 INFO - TEST-OK | dromaeo_css | took 1113007ms 15:06:14 INFO - TEST-START | kraken 15:06:14 INFO - operating with platform_type : mac_ 15:06:14 INFO - Initialising browser for kraken test... 15:06:14 INFO - TEST-INFO | started process 2342 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp5gXhZG/profile http://localhost:50001/getInfo.html) 15:06:16 INFO - PROCESS | 2342 | __metrics Screen width/height:1600/1200 15:06:16 INFO - PROCESS | 2342 | colorDepth:24 15:06:16 INFO - PROCESS | 2342 | Browser inner width/height: 1024/689 15:06:16 INFO - PROCESS | 2342 | __metrics 15:06:16 INFO - PROCESS | 2342 | 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" 15:06:16 INFO - PROCESS | 2342 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:06:16 INFO - TEST-INFO | 2342: exit 0 15:06:16 INFO - Browser initialized. 15:06:16 INFO - Running cycle 1/1 for kraken test... 15:06:17 INFO - TEST-INFO | started process 2344 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp5gXhZG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:06:28 INFO - PROCESS | 2344 | RSS: Main: 242982912 15:06:28 INFO - PROCESS | 2344 | 15:06:31 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50001/tests/kraken/driver.html?testName=audio-beat-detection) 15:06:32 INFO - PROCESS | 2344 | RSS: Main: 290955264 15:06:32 INFO - PROCESS | 2344 | 15:06:34 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50001/tests/kraken/driver.html?testName=audio-dft) 15:06:35 INFO - PROCESS | 2344 | RSS: Main: 301195264 15:06:35 INFO - PROCESS | 2344 | 15:06:38 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50001/tests/kraken/driver.html?testName=audio-fft) 15:06:39 INFO - PROCESS | 2344 | RSS: Main: 286547968 15:06:39 INFO - PROCESS | 2344 | 15:06:41 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50001/tests/kraken/driver.html?testName=audio-oscillator) 15:06:41 INFO - PROCESS | 2344 | RSS: Main: 289562624 15:06:41 INFO - PROCESS | 2344 | 15:06:44 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50001/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:06:44 INFO - PROCESS | 2344 | RSS: Main: 289841152 15:06:44 INFO - PROCESS | 2344 | 15:06:47 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50001/tests/kraken/driver.html?testName=imaging-darkroom) 15:06:48 INFO - PROCESS | 2344 | RSS: Main: 289091584 15:06:48 INFO - PROCESS | 2344 | 15:06:50 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50001/tests/kraken/driver.html?testName=imaging-desaturate) 15:06:51 INFO - PROCESS | 2344 | RSS: Main: 291123200 15:06:51 INFO - PROCESS | 2344 | 15:06:54 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50001/tests/kraken/driver.html?testName=json-parse-financial) 15:06:54 INFO - PROCESS | 2344 | RSS: Main: 289505280 15:06:54 INFO - PROCESS | 2344 | 15:06:56 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50001/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:06:57 INFO - PROCESS | 2344 | RSS: Main: 325148672 15:06:57 INFO - PROCESS | 2344 | 15:06:58 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:06:59 INFO - PROCESS | 2344 | RSS: Main: 325595136 15:06:59 INFO - PROCESS | 2344 | 15:07:01 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:07:02 INFO - PROCESS | 2344 | RSS: Main: 331067392 15:07:02 INFO - PROCESS | 2344 | 15:07:04 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:07:05 INFO - PROCESS | 2344 | RSS: Main: 333443072 15:07:05 INFO - PROCESS | 2344 | 15:07:07 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:07:08 INFO - PROCESS | 2344 | RSS: Main: 333287424 15:07:08 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50001/tests/kraken/driver.html?testName=ai-astar) 15:07:10 INFO - PROCESS | 2344 | RSS: Main: 392916992 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | __start_tp_report 15:07:10 INFO - PROCESS | 2344 | _x_x_mozilla_page_load 15:07:10 INFO - PROCESS | 2344 | _x_x_mozilla_page_load_details 15:07:10 INFO - PROCESS | 2344 | |i|pagename|runs| 15:07:10 INFO - PROCESS | 2344 | |0;ai-astar;112;109;111;113;90;154;167;163;111;111 15:07:10 INFO - PROCESS | 2344 | |1;audio-beat-detection;160;120;146;155;119;145;141;122;194;146 15:07:10 INFO - PROCESS | 2344 | |2;audio-dft;188;189;186;185;185;187;188;183;185;184 15:07:10 INFO - PROCESS | 2344 | |3;audio-fft;73;77;84;79;56;57;58;80;79;55 15:07:10 INFO - PROCESS | 2344 | |4;audio-oscillator;97;124;170;106;79;86;111;78;79;116 15:07:10 INFO - PROCESS | 2344 | |5;imaging-gaussian-blur;86;92;86;93;86;92;85;92;86;92 15:07:10 INFO - PROCESS | 2344 | |6;imaging-darkroom;94;94;94;94;94;95;95;95;94;93 15:07:10 INFO - PROCESS | 2344 | |7;imaging-desaturate;92;85;71;125;137;99;84;88;85;85 15:07:10 INFO - PROCESS | 2344 | |8;json-parse-financial;57;54;54;53;56;55;56;64;55;52 15:07:10 INFO - PROCESS | 2344 | |9;json-stringify-tinderbox;65;75;77;72;71;70;68;68;69;68 15:07:10 INFO - PROCESS | 2344 | |10;stanford-crypto-aes;83;82;84;83;85;82;82;80;102;83 15:07:10 INFO - PROCESS | 2344 | |11;stanford-crypto-ccm;124;145;120;132;120;121;123;126;124;123 15:07:10 INFO - PROCESS | 2344 | |12;stanford-crypto-pbkdf2;141;125;138;137;139;122;135;137;134;136 15:07:10 INFO - PROCESS | 2344 | |13;stanford-crypto-sha256-iterative;63;89;96;78;67;68;90;58;62;68 15:07:10 INFO - PROCESS | 2344 | __end_tp_report 15:07:10 INFO - PROCESS | 2344 | __start_cc_report 15:07:10 INFO - PROCESS | 2344 | _x_x_mozilla_cycle_collect,1227 15:07:10 INFO - PROCESS | 2344 | __end_cc_report 15:07:10 INFO - PROCESS | 2344 | __startTimestamp1456528030484__endTimestamp 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | ------- Summary: start ------- 15:07:10 INFO - PROCESS | 2344 | Number of tests: 14 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#0] ai-astar Cycles:10 Average:124.10 Median:133.50 stddev:26.71 (20.0%) stddev-sans-first:27.97 15:07:10 INFO - PROCESS | 2344 | Values: 112.0 109.0 111.0 113.0 90.0 154.0 167.0 163.0 111.0 111.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:146.00 stddev:22.54 (15.4%) stddev-sans-first:23.23 15:07:10 INFO - PROCESS | 2344 | Values: 160.0 120.0 146.0 155.0 119.0 145.0 141.0 122.0 194.0 146.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#2] audio-dft Cycles:10 Average:186.00 Median:186.50 stddev:1.94 (1.0%) stddev-sans-first:1.92 15:07:10 INFO - PROCESS | 2344 | Values: 188.0 189.0 186.0 185.0 185.0 187.0 188.0 183.0 185.0 184.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#3] audio-fft Cycles:10 Average:69.80 Median:78.00 stddev:11.78 (15.1%) stddev-sans-first:12.44 15:07:10 INFO - PROCESS | 2344 | Values: 73.0 77.0 84.0 79.0 56.0 57.0 58.0 80.0 79.0 55.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:78.50 stddev:28.38 (36.2%) stddev-sans-first:29.97 15:07:10 INFO - PROCESS | 2344 | Values: 97.0 124.0 170.0 106.0 79.0 86.0 111.0 78.0 79.0 116.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:92.00 stddev:3.40 (3.7%) stddev-sans-first:3.43 15:07:10 INFO - PROCESS | 2344 | Values: 86.0 92.0 86.0 93.0 86.0 92.0 85.0 92.0 86.0 92.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 15:07:10 INFO - PROCESS | 2344 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 93.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:85.00 stddev:20.37 (24.0%) stddev-sans-first:21.58 15:07:10 INFO - PROCESS | 2344 | Values: 92.0 85.0 71.0 125.0 137.0 99.0 84.0 88.0 85.0 85.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:3.31 (6.0%) stddev-sans-first:3.47 15:07:10 INFO - PROCESS | 2344 | Values: 57.0 54.0 54.0 53.0 56.0 55.0 56.0 64.0 55.0 52.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:3.59 (5.1%) stddev-sans-first:3.26 15:07:10 INFO - PROCESS | 2344 | Values: 65.0 75.0 77.0 72.0 71.0 70.0 68.0 68.0 69.0 68.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:83.00 stddev:6.26 (7.5%) stddev-sans-first:6.61 15:07:10 INFO - PROCESS | 2344 | Values: 83.0 82.0 84.0 83.0 85.0 82.0 82.0 80.0 102.0 83.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:124.00 stddev:7.60 (6.1%) stddev-sans-first:8.03 15:07:10 INFO - PROCESS | 2344 | Values: 124.0 145.0 120.0 132.0 120.0 121.0 123.0 126.0 124.0 123.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.40 Median:137.00 stddev:6.11 (4.5%) stddev-sans-first:6.00 15:07:10 INFO - PROCESS | 2344 | Values: 141.0 125.0 138.0 137.0 139.0 122.0 135.0 137.0 134.0 136.0 15:07:10 INFO - PROCESS | 2344 | 15:07:10 INFO - PROCESS | 2344 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:73.00 stddev:13.43 (18.4%) stddev-sans-first:13.65 15:07:10 INFO - PROCESS | 2344 | Values: 63.0 89.0 96.0 78.0 67.0 68.0 90.0 58.0 62.0 68.0 15:07:10 INFO - PROCESS | 2344 | -------- Summary: end -------- 15:07:10 INFO - PROCESS | 2344 | 15:07:11 INFO - TEST-INFO | 2344: exit 0 15:07:11 INFO - TEST-OK | kraken | took 57188ms 15:07:11 INFO - SUITE-END | took 1170s 15:07:11 INFO - Completed test suite (00:19:30) 15:07:11 INFO - Working with test: dromaeo_css 15:07:11 INFO - Generating results file: dromaeo_css 15:07:11 INFO - Working with test: kraken 15:07:11 INFO - Generating results file: kraken 15:07:11 INFO - javascript benchmark 15:07:11 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:07:11 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 15:07:11 INFO - process_Request line: dromaeo_css 7386.18 graph.html#tests=[[72,64,59]] 15:07:11 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:07:11 INFO - process_Request line: kraken 1452.20 graph.html#tests=[[232,64,59]] 15:07:11 INFO - RETURN: dromaeo_css: 7386.18 15:07:11 INFO - RETURN: kraken: 1452.20 15:07:11 INFO - javascript benchmark 15:07:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3396.337714145741, 3419.504171895075, 3429.7942123472576, 3441.7590768646187, 3479.895603131907, 5558.221848941152, 5587.329520457546, 5621.971890140554, 5631.352394474631, 5659.745311460983, 4623.445186577607, 5619.129034999572, 5646.491815736583, 5710.600257981943, 5743.4543718346695, 5015.0, 5052.989402119581, 5084.77118529667, 5086.643934924557, 5247.1866860636665, 5166.9224961625605, 5187.429382767892, 5200.401953775307, 5229.294045303874, 5237.973810130944, 5054.267131265964, 5088.313077734496, 5106.821261255838, 5153.3558305211845, 5156.948430515685, 5119.488051194888, 5121.00140472608, 5123.846284611468, 5142.845714628567, 5182.300389447414, 5055.974720126413, 5060.202725487463, 5082.822101226477, 5103.693778373309, 5110.233464980245, 5409.377921539034, 5498.945010549924, 5514.751836167382, 5553.389127196006, 5556.666600004012, 5633.774649014034, 5826.572489739999, 6151.569390142692, 6155.045967874987, 6178.042403427476, 6205.913965056097, 6224.470919971808, 6235.407636274546, 6242.313345531988, 6268.968655156741, 5589.8323050308145, 5675.5459563234845, 5682.204491371212, 5682.829515114553, 5689.516391106719, 6187.257529096537, 6196.938030619636, 6205.937940620626, 6211.0, 6221.875562488724, 6013.909791353133, 6055.273367195965, 6057.575969682168, 6116.3577824328695, 6144.262688477322, 6109.1010297800785, 6111.297200821962, 6154.938450615526, 6213.720382582795, 6251.031090180938, 6135.477655820289, 6138.048602466625, 6142.679323945373, 6149.7847575334645, 6151.7539298428455, 6122.3778139773995, 6145.985912324506, 6158.322584515699, 6163.784267550704, 6170.10533472653, 4162.666986641061, 4255.76593287366, 4676.485586585541, 4812.422509298906, 4820.469748327681, 4320.114376552862, 4398.626116780109, 4407.162639098625, 4417.845375411845, 4425.756583387944, 5649.802256920988, 5671.602987790898, 5708.20085188074, 5771.9711401443565, 5776.104703770839, 6225.346338634378, 6237.345078766755, 6242.65665388408, 6260.311365749764, 6261.686915654199, 5625.409332020161, 5695.886082278331, 5732.054211055212, 5738.770449181986, 5760.51035661965, 5166.147585648401, 5280.709560974106, 5280.7359632019015, 5289.259503669489, 5297.231901374279, 6203.658798766111, 6223.284322302902, 6223.906641400383, 6242.687865606701, 6260.812175634738, 6121.47967422774, 6136.610985192662, 6154.046122850965, 6200.441960223601, 6203.2245969253845, 6198.907016394667, 6200.038993955944, 6202.56582039271, 6214.751409943732, 6221.035739460391, 5970.432808883149, 6127.172831667668, 6129.632222066691, 6150.538709596709, 6159.1069294951085, 4748.952510474851, 5848.6783226921225, 6118.588722523033, 6135.233095863017, 6144.463884028993, 5805.506531944832, 5919.792807251725, 5960.374160713236, 5965.164876917148, 5975.34271230173, 5942.168096466585, 5952.404759524013, 5957.791477298274, 5961.642301461926, 6001.789937352171, 5388.515033646991, 5711.0, 5804.0, 5809.331926828299, 5812.389699081536, 6031.909521357095, 6035.607685500604, 6036.366181550874, 6054.182685337423, 6103.786367477117, 5785.305763308262, 5799.536037117105, 5821.825345239649, 5830.970845145747, 5862.062070068769], "unit": "score", "name": "dojo.html", "value": 5600.2630056068465}, {"lowerIsBetter": false, "replicates": [482.6356101143636, 495.50697056428845, 565.8287345195447, 584.4914924016106, 605.122572270208, 36418.817905910466, 37067.0, 37105.44341834875, 37114.886553403405, 37230.999999999935, 38751.0, 39121.413178802344, 39147.60852391482, 39241.41137882934, 39254.41118383219, 9435.716928492138, 9660.75848103796, 9668.806623867518, 9670.274729395289, 9683.515824208796, 9453.33826632136, 9624.903750962505, 9625.085616866421, 9630.614775408976, 9636.421814691106, 9610.855837162448, 9644.807103857918, 9685.75785605362, 9704.029597040275, 9717.659881904136, 9616.663416780415, 9665.420074795464, 9675.516224188763, 9677.516124193762, 9681.322307438517, 9561.187299079587, 9654.03459654036, 9655.758606034906, 9659.613615455373, 9673.70978870635, 38229.42655860164, 38523.8073809632, 38571.22857542861, 38588.614113858785, 38594.0, 3075.492543730282, 3152.4325621388143, 3259.41330560499, 3326.933461330784, 3480.216951185963, 27229.04698335549, 27697.446051079067, 27925.16224513268, 28089.297767555974, 28314.43371132566, 2698.055680511825, 2747.8900843966217, 2760.641116654822, 2793.5530315149476, 2833.4474777418413, 376265.0, 378449.0, 379099.0, 379224.0, 380052.0, 43157.0, 59541.70229148826, 59859.0, 60044.699776501715, 60124.39875601276, 41970.0, 42019.78990105091, 42059.0, 42133.469312256646, 42229.57770422318, 15028.549143525712, 15313.693726125415, 15333.386664533517, 15340.463083792238, 15351.15568643713, 12341.75316493665, 12384.504619815287, 12425.943794777364, 12435.564755233523, 12470.0, 13679.042467027411, 13853.930730346283, 13991.370388332549, 13995.95030372726, 14097.321508913274, 6230.501559875199, 6261.78083767066, 6288.685565721696, 6294.181756371642, 6300.779472718433, 850.927671147947, 914.7072936660205, 915.4507295622573, 977.4233202410612, 1001.6594357918198, 27905.581416278772, 28077.578836317472, 28297.0, 28557.857210713813, 28735.0, 1079.6005477973201, 1084.8372744088292, 1105.2594761509808, 1146.9025132863633, 1158.2760774515928, 14353.85646143546, 14364.569062928129, 14638.902082343653, 14648.267586620626, 14712.970092093665, 497609.0, 498288.0, 499419.0290485461, 499840.0, 503304.0, 54400.0, 54743.45256547463, 54752.0, 55054.17418738722, 55143.72428137834, 1280.5582074184579, 1395.9092658977133, 1421.8862491000696, 1431.2485944879022, 1442.776369662704, 27274.318142046606, 27835.304117397227, 28347.85826070939, 28900.27749306284, 29047.0, 44134.55865441304, 44848.32727509025, 45315.32027019663, 45362.0, 45526.54473455356, 359836.0, 361295.0, 362014.0, 362082.0, 362095.0, 27413.17760467189, 31317.8434107828, 31769.682303176673, 32145.0, 32222.033338999867, 39270.01824954399, 39763.0, 40030.59969400269, 40083.39874901937, 40490.9877253071, 32897.671023290415, 33361.66553337839, 33419.83290083534, 33858.32283354369, 34205.828970854986, 32877.50683739794, 32931.0, 33202.833985829915, 33224.501632476014, 33759.81840635566, 34512.65487345094, 34725.0, 35535.0, 35548.64451355454, 35682.46476302909, 30151.849240753654, 30980.84509577528, 31048.844755776077, 31126.844365778932, 31547.842260788548, 19174.904125479283, 23166.42083947915, 23346.883265583565, 23646.527069458858, 23972.88013559921], "unit": "score", "name": "ext.html", "value": 18229.39965429169}, {"lowerIsBetter": false, "replicates": [310.7234561240496, 350.77199820116937, 353.68698701649043, 355.1635897461478, 358.9013021419111, 19206.519837004063, 19234.942078185693, 21386.25148119816, 21462.819544925063, 21607.0, 20626.1749530019, 20936.057877395517, 20945.05747241374, 21212.469688257803, 21253.25613603524, 5803.375054984601, 6073.362296958822, 6148.784792532262, 6162.568620196588, 6163.383661633846, 4246.2993606055115, 4306.827726890936, 5986.850328741794, 6280.214973128359, 6556.770513032045, 6469.417752402306, 6481.967590162042, 6498.935010649904, 6505.772297969572, 6511.772087976922, 6318.715657795387, 6631.502637302174, 6642.103316052319, 6646.468282537409, 6652.334766523358, 3726.6087060858763, 5372.435894231127, 5601.243832082658, 5617.634853734535, 5718.771249150029, 24729.887155078064, 24966.625500617505, 25136.748632513623, 25501.0, 25794.742052579422, 2131.8507704460694, 2149.3874245840016, 2210.204326442479, 2218.267971569378, 2238.1383167480635, 14550.99145926535, 14618.70762584753, 14778.26108694561, 14939.0, 15055.698886022328, 1682.3691115831564, 1730.2732852202105, 1796.7843858736906, 1814.2198854492563, 1873.4379686094253, 93146.0, 102619.48690256575, 105317.0, 106454.0, 107171.0, 10774.32997885329, 11122.054625356774, 11279.210455268216, 11303.604373846876, 11421.486033128529, 13402.731945361038, 13495.460181592824, 13509.459621615222, 13531.864681353258, 13534.661633459242, 8427.115152908857, 8568.785780355416, 8599.828003439896, 8668.956655216683, 8685.305175585938, 8730.301575873915, 8763.56182190888, 8779.165979231962, 8863.113688631085, 8865.778355541162, 3779.9244015120094, 3839.500864887547, 4443.555644435531, 4479.216137176047, 4509.842155524541, 1733.2633630706898, 1779.2349289805509, 1821.3807305516189, 1831.954201144982, 1846.7783865935905, 871.4988881393224, 911.562450023979, 925.4909799610322, 959.7024922274118, 1014.7158795537262, 18653.533661658297, 18977.33579324717, 19735.724209621454, 20218.393448196577, 20950.685739713917, 872.7425409504084, 928.5218112671979, 928.5218112671979, 951.6811868024289, 970.9223262139012, 8948.642054317885, 11014.377985102425, 11064.17018723599, 11125.443727813577, 11143.832842507369, 574617.0, 577433.0, 582665.0, 586651.8442053379, 594630.0, 95808.56287155698, 116647.0, 117474.0, 117729.0, 117897.0, 971.3249291742312, 971.4220039076738, 986.6201512417628, 987.2842189412762, 987.4026214140486, 17978.5505362367, 17980.0, 17995.2801887923, 18073.096345182952, 18409.81590184081, 31125.22186945254, 50064.749676251384, 50398.19624350666, 51324.48675513197, 51843.0, 63421.682891585246, 65984.35456358391, 66843.33156668565, 67281.9538534602, 74734.0, 7878.566678832719, 8019.599020048858, 8207.384446166441, 8301.83396332082, 8371.246587807127, 9321.906780932375, 12379.876201237872, 13240.20558766477, 13597.456101756216, 13775.724485510033, 11027.53900031842, 11330.886691133313, 11385.772284554097, 11403.657890263305, 11894.167408281337, 11186.83219751688, 11209.159313051718, 11673.94934455903, 11716.765664686489, 11815.527378904748, 11515.942420287845, 11634.301941883514, 11728.296302221894, 11941.044716422839, 12498.875011249771, 7990.0, 8072.152423995631, 8135.552544609866, 8414.83170336602, 8448.91551084506, 11345.978861902468, 11462.484188211718, 11511.712207194887, 11742.471588778355, 11924.642260732191], "unit": "score", "name": "jquery.html", "value": 9482.983579897034}, {"lowerIsBetter": false, "replicates": [1366.6310096274012, 1507.223779753427, 1532.3487517804933, 1763.3387479695114, 1954.3550628292664, 7221.458390620698, 7939.8809017864705, 8441.831163376728, 8609.0, 8625.956870215648, 8335.499870007789, 8612.827743445127, 8618.439801412904, 8619.353548483874, 8620.396572239946, 2190.6823510590953, 2423.72127205371, 2473.54239465699, 2503.4367267364787, 2508.7867531259867, 2490.8007359411204, 2504.8246622736415, 2508.0, 2510.3598582361474, 2517.937051573707, 2397.496525729599, 2416.8187385946035, 2416.8791560421996, 2417.6615273861676, 2422.4186195313087, 2379.6430535419654, 2397.184957114572, 2438.2197696737053, 2495.9500809983697, 2497.3881399057345, 2479.4049428137296, 2490.0786708917635, 2492.9252122436174, 2504.0860086068637, 2516.043903316728, 8730.301575873978, 8796.384253102286, 8813.603387847563, 8818.206361427438, 8834.160754728355, 5201.453847346012, 5374.758135883894, 5408.296921400232, 5418.106012507931, 5418.566514678856, 3267.9183020424443, 3529.2764983178386, 3584.6557540922154, 3725.9068523286865, 3726.1988672435286, 1052.4895425718596, 1069.315637991686, 1079.881213066562, 1088.472091035847, 1117.4468638024148, 11588.884111158864, 11797.174197806156, 11829.585964491287, 11843.88156118445, 11846.703832404173, 4839.879003024953, 4920.901581968412, 4985.4266759323145, 5009.5741861941415, 5024.598032157419, 10110.24173187014, 10124.088832005007, 10139.746506337253, 10155.543000564992, 10175.135113515285, 5490.945090549043, 5497.642653227527, 5513.117901135799, 5542.501174894279, 5542.61201715876, 4365.476142862877, 4494.258447356149, 4498.100379924028, 4531.864044078683, 4727.669063165546, 3670.4861319415304, 3689.815509224528, 3956.1098752780968, 3994.1013272013565, 4035.0, 1286.8327117474669, 1300.5058077930325, 1304.4912484131196, 1316.5457917018614, 1325.5294370498334, 1100.7248187953012, 1116.8715597706205, 1123.095907794217, 1273.8853503184757, 1372.293268966483, 8010.559419231999, 8074.0, 8124.390670699721, 8130.75607731769, 8303.626336814857, 1097.3635291530893, 1117.80997230471, 1177.7526719389025, 1178.6287319494504, 1204.8253003314646, 6194.38056194377, 6213.285472170668, 6285.308616052231, 6286.874262514815, 6290.4653104485715, 10139.64511242103, 10458.52936617854, 10579.471026448648, 10579.682609521727, 10590.940905909501, 10257.974202579682, 10281.074703276741, 10323.019313165387, 10431.739206519898, 10551.630692925864, 7690.692372305045, 8305.916940830635, 8330.416870819106, 8334.374921880883, 8352.997640283083, 7784.377249820114, 7859.646315915683, 7907.446478746662, 7923.405744569065, 7953.204679532, 9295.860562091439, 9426.528673566432, 9511.714648560554, 9574.712758617252, 9682.612695492102, 9540.284478664267, 9940.45327506965, 9989.500524973577, 10002.699919002442, 10019.098281154731, 6363.904541431974, 6530.869382612226, 6921.82695432598, 6980.755673551402, 7084.822879428056, 8135.9186408135165, 8181.6318265677, 8210.3021243195, 8222.671093156207, 8228.753137405887, 7213.422926165999, 7262.164851041986, 7286.817829554304, 7395.445341599294, 7405.62971851416, 7157.64211789398, 7203.0, 7229.313215244543, 7232.559996805486, 7248.238934911969, 7579.052618422697, 7646.311831935152, 7661.425393095428, 7662.846743064996, 7688.538687678757, 6945.756898508528, 7047.823804404931, 7050.964745176241, 7054.435645148478, 7055.153381594241, 6815.250322464624, 7064.858702826017, 7078.398336141279, 7119.822004449723, 7169.390601798906], "unit": "score", "name": "mootools.html", "value": 4901.912352034071}, {"lowerIsBetter": false, "replicates": [316.8194129346273, 318.87882604610246, 328.56957385824586, 329.42844165373066, 332.26071989822634, 6579.0, 6646.634435106073, 6653.700583473743, 6664.733410663568, 6665.40011398974, 6492.091107244978, 6532.020196970457, 6546.639934803584, 6554.639494827783, 6565.277819439858, 4565.817367305303, 4643.744594047326, 4707.576318131368, 4830.444498882621, 4878.951210487903, 4827.758612069382, 4831.782569784351, 4847.709137451764, 4859.246816743411, 4863.294822250771, 4772.80908763649, 4777.713337199779, 4787.784549695272, 4794.400699912511, 4802.351682522867, 4580.152671755719, 4666.346711460398, 4752.904941901143, 4774.212254977924, 4783.617310615143, 4051.554329023805, 4070.409790580349, 4076.103257283393, 4102.384642303648, 4106.691998100155, 6208.099825525273, 6208.6585237811905, 6282.403171698681, 6287.685615719196, 6326.323524266073, 5641.238432811559, 5657.886842263173, 5668.489835914787, 5686.943130568683, 5694.800681976111, 5621.747021384048, 6938.826529336757, 6939.271376505445, 6944.4097251733665, 6960.199577048654, 4294.742315461082, 4469.150861336336, 4470.061287129706, 4482.618977386926, 4482.8655140345845, 7220.169680486706, 7357.595332256724, 7360.55836649808, 7367.336939675455, 7375.926240737577, 4574.428196475441, 4586.334981427741, 4591.081783643284, 4592.7933243004145, 4594.241950078266, 4647.674662773641, 4649.674522783373, 4665.370175026396, 4668.043051174501, 4670.906581868411, 5545.195946587723, 5553.861153471195, 5554.583406244547, 5561.721913904369, 5582.0789569721355, 6662.200535935719, 6972.825679357955, 6994.33553812387, 7039.54242974205, 7040.401565866959, 1604.8074231092344, 1607.469535053453, 1616.7574863770528, 1618.6358069434518, 1621.805383354005, 815.7471183933, 1000.9299349045642, 1020.6070662794878, 1027.8972102789662, 1050.1231471721046, 6448.645324507197, 6528.706208220641, 6566.015097735282, 6566.376194261537, 6570.178727659043, 7183.604901730455, 7204.279572042754, 7207.675654595556, 7211.89182162268, 7225.891611625725, 5406.54044406222, 6523.739050438025, 6523.7716679915975, 6525.836854078591, 6527.608343499405, 3760.2667479841716, 3813.637704418076, 3818.0645741793437, 3821.732478726518, 3827.3110840048503, 7313.707451701979, 7586.165521792598, 7595.354394876387, 7599.164091949881, 7629.2752188542, 7450.0, 7532.849343013109, 7579.355754760907, 7579.583122928292, 7596.2023987481625, 7629.0845098588525, 7643.464957452925, 7645.5794931279315, 7653.9234607654325, 7660.80847978805, 7027.789166325018, 7307.1231452226075, 7325.963370183115, 7328.413726901942, 7359.300866417681, 7154.427645788429, 7216.675249613675, 7222.09723784527, 7222.783316500513, 7229.891551626835, 7612.352949999312, 7625.932369468168, 7636.656350464132, 7646.197149299466, 7660.923390766244, 4757.500462451393, 4796.37647105874, 4800.6159507238635, 4802.303665968345, 4803.687760295569, 7003.859922801414, 7158.212596614264, 7208.71165153388, 7209.747658831916, 7211.71153153868, 6964.129483814523, 7004.268335349116, 7015.824604384931, 7020.613866237406, 7021.648917554, 6980.790576282517, 7000.789976300719, 7006.789796306119, 7026.402755765613, 7043.436525078084, 6821.454283657395, 6826.829329266808, 6841.350071743176, 6852.177738671391, 6854.897176542256, 6759.188897332351, 7021.719131234693, 7032.648367581498, 7044.978478120746, 7065.01089847432, 6546.705398256994, 6683.264840867598, 6693.765718199839, 6723.4957378197805, 6872.62200578953], "unit": "score", "name": "prototype.html", "value": 5111.140453530477}, {"lowerIsBetter": false, "replicates": [643.7875501084636, 655.4657953767679, 666.167290886392, 719.5071376107367, 750.5909279442704, 14237.572872813818, 14427.134371937665, 14450.0, 14457.783133252997, 14479.203643799587, 12143.0, 12926.353682315894, 12944.352782360891, 12973.897218736418, 12981.545645902372, 858.6694122762734, 863.3999370437548, 872.2455076358935, 872.3849685971375, 877.863931090682, 861.418542483824, 866.9826603467927, 871.4509858788986, 873.3449912565576, 874.9825003499926, 918.9678361257357, 921.3642586615248, 921.9535826836521, 924.5192499900081, 924.6162842420388, 908.9045650206732, 909.6634245329204, 911.0388540090169, 912.3613470570594, 912.5254867468946, 676.2696288008939, 682.7849227493324, 684.6576711644178, 687.298955065835, 688.4939569416475, 12371.13402061856, 12411.689707757378, 12414.56549020789, 12414.875851241462, 12426.316552589604, 11132.777344453147, 11354.943225283903, 11359.204855660108, 11443.656690299304, 11479.368634725086, 9475.336726429152, 9679.419234845931, 9813.754656133582, 9946.651867184613, 9954.004599539989, 3421.4183588789965, 3429.248122919531, 3467.4452087666104, 3486.041338631871, 3559.626239244868, 75336.62331688362, 76349.61825190838, 76734.23265767327, 76836.23163768346, 79044.60477697689, 8252.33981281496, 8366.414350995494, 8387.287080598095, 8389.286910612544, 8473.406861519758, 19063.90468047651, 19075.950822704883, 19089.522761931064, 19099.809001910082, 19122.04389780533, 7387.556746595222, 7388.187299397062, 7434.256574342522, 7801.258880406353, 7830.6084695764985, 4087.8773636790943, 4120.75275483472, 4170.666346692257, 4179.728317659343, 4213.410122582841, 3523.0487768302414, 3578.051816268691, 3578.3916734155255, 3582.0865679251624, 3585.2650206707563, 498.9501049895054, 638.8594509207967, 649.7530938243438, 659.8779225843234, 691.4322737636619, 481.32614339923686, 523.8088097844336, 536.6458137629146, 565.5390856451978, 598.4554055809193, 8030.879536806952, 8077.596120193967, 8322.16778322175, 8394.790130246793, 8407.2433480987, 472.6384315998264, 518.157993260951, 533.6211289984154, 573.6959411511905, 596.397638385234, 3388.847501862422, 3470.704990075872, 3908.765474071508, 3933.3706606943324, 4065.8780236592356, 82851.0, 83895.58052209821, 83933.74099388513, 84182.31635367381, 91746.62380064305, 79890.0, 80511.7923231152, 81517.0, 81914.59042704749, 91601.54199228961, 70893.0, 73031.0, 73614.26385736182, 73833.0, 76600.5739368669, 8396.32829373661, 8472.0, 8627.91372086271, 8681.956590217262, 8757.824843503222, 50083.0, 51673.48326516687, 51689.741551293504, 52709.0, 55001.72499137479, 71208.64395678196, 71704.28295716977, 71707.64146179236, 71717.28282717106, 71930.64034679793, 9810.901890980998, 10765.407902565434, 10831.404272764757, 10974.012338889539, 11642.068634509389, 9207.283851264026, 10609.840852387375, 10625.25623206368, 10953.83569246478, 11086.168537359892, 10928.836067458835, 11400.600978965696, 11725.944664979852, 11866.110041747077, 12366.690832729253, 11209.49557269941, 11237.662870113909, 11591.304521728724, 11608.941955290169, 11649.767004660029, 11229.27009744364, 11441.771164576829, 11446.599369022044, 11562.76874462523, 12214.450349734461, 8894.688685895962, 9127.589258483516, 9238.30712696564, 9298.395604285699, 9549.618015279311, 8911.866322005304, 9163.679271225476, 9447.622095116118, 9677.467515340168, 9876.50617469138], "unit": "score", "name": "yui.html", "value": 6693.879634518979}], "name": "dromaeo_css", "value": 7386.181531084572}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 111.0, 113.0, 90.0, 154.0, 167.0, 163.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 124.1}, {"lowerIsBetter": true, "replicates": [160.0, 120.0, 146.0, 155.0, 119.0, 145.0, 141.0, 122.0, 194.0, 146.0], "unit": "score", "name": "audio-beat-detection", "value": 144.8}, {"lowerIsBetter": true, "replicates": [188.0, 189.0, 186.0, 185.0, 185.0, 187.0, 188.0, 183.0, 185.0, 184.0], "unit": "score", "name": "audio-dft", "value": 186.0}, {"lowerIsBetter": true, "replicates": [73.0, 77.0, 84.0, 79.0, 56.0, 57.0, 58.0, 80.0, 79.0, 55.0], "unit": "score", "name": "audio-fft", "value": 69.8}, {"lowerIsBetter": true, "replicates": [97.0, 124.0, 170.0, 106.0, 79.0, 86.0, 111.0, 78.0, 79.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 104.6}, {"lowerIsBetter": true, "replicates": [86.0, 92.0, 86.0, 93.0, 86.0, 92.0, 85.0, 92.0, 86.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.0}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 94.2}, {"lowerIsBetter": true, "replicates": [92.0, 85.0, 71.0, 125.0, 137.0, 99.0, 84.0, 88.0, 85.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [57.0, 54.0, 54.0, 53.0, 56.0, 55.0, 56.0, 64.0, 55.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 55.6}, {"lowerIsBetter": true, "replicates": [65.0, 75.0, 77.0, 72.0, 71.0, 70.0, 68.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.3}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 84.0, 83.0, 85.0, 82.0, 82.0, 80.0, 102.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.6}, {"lowerIsBetter": true, "replicates": [124.0, 145.0, 120.0, 132.0, 120.0, 121.0, 123.0, 126.0, 124.0, 123.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.8}, {"lowerIsBetter": true, "replicates": [141.0, 125.0, 138.0, 137.0, 139.0, 122.0, 135.0, 137.0, 134.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.4}, {"lowerIsBetter": true, "replicates": [63.0, 89.0, 96.0, 78.0, 67.0, 68.0, 90.0, 58.0, 62.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 73.9}], "name": "kraken", "value": 1452.2}]} 15:07:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1452.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7386.18"}}} 15:07:11 INFO - Return code: 0 15:07:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:07:11 INFO - # TBPL SUCCESS # 15:07:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:07:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:07:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:07:11 INFO - 2016-02-26 15:07:11.864 screenresolution[2348:19840] starting screenresolution argv=screenresolution get 15:07:11 INFO - 2016-02-26 15:07:11.866 screenresolution[2348:19840] Display 0: 1600x1200x32@60 15:07:11 INFO - 2016-02-26 15:07:11.872 screenresolution[2349:19843] starting screenresolution argv=screenresolution list 15:07:11 INFO - Available Modes on Display 0 15:07:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:07:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:07:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:07:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:07:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:07:12 INFO - Intel Iris: 15:07:12 INFO - Chipset Model: Intel Iris 15:07:12 INFO - Type: GPU 15:07:12 INFO - Bus: Built-In 15:07:12 INFO - VRAM (Dynamic, Max): 1536 MB 15:07:12 INFO - Vendor: Intel (0x8086) 15:07:12 INFO - Device ID: 0x0a2e 15:07:12 INFO - Revision ID: 0x0009 15:07:12 INFO - Displays: 15:07:12 INFO - Display: 15:07:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:07:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:07:12 INFO - Display Serial Number: 1600x1200 60 15:07:12 INFO - Main Display: Yes 15:07:12 INFO - Mirror: Off 15:07:12 INFO - Online: Yes 15:07:12 INFO - Rotation: Supported 15:07:12 INFO - Return code: 0 15:07:12 INFO - Running post-action listener: _resource_record_post_action 15:07:12 INFO - Running post-run listener: _resource_record_post_run 15:07:13 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 253952; Write bytes: 706033152; Read time: 2; Write time: 1903 15:07:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386959872; Write bytes: 380511744; Read time: 29513; Write time: 716 15:07:13 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 253952; Write bytes: 300113920; Read time: 2; Write time: 1132 15:07:13 INFO - Running post-run listener: _upload_blobber_files 15:07:13 INFO - Blob upload gear active. 15:07:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:07:13 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 15:07:13 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'] 15:07:13 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 15:07:13 INFO - (blobuploader) - INFO - Open directory for files ... 15:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:07:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:14 INFO - (blobuploader) - INFO - Done attempting. 15:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:07:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:24 INFO - (blobuploader) - INFO - Done attempting. 15:07:24 INFO - (blobuploader) - INFO - Iteration through files over. 15:07:24 INFO - Return code: 0 15:07:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:07:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:07:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f6edb5345800c3c68f5cdedb9b9714abbe49e3f84b9bbab4e4106bf80db573bffe00b8fcef8c36f80e58d3ec7f08980a67fe4c0d1c5e44bd3a1fbfc160b6076", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f17e87be8e2c5801bd9d300fc364201b9bd8d42d1a5f91160fe8bb025451d95cb4f30cc25be710db8f4cf654f69cbb131e70123ed9c05fadd6326a597ecb20b2"} 15:07:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:07:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:07:24 INFO - Contents: 15:07:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f6edb5345800c3c68f5cdedb9b9714abbe49e3f84b9bbab4e4106bf80db573bffe00b8fcef8c36f80e58d3ec7f08980a67fe4c0d1c5e44bd3a1fbfc160b6076", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f17e87be8e2c5801bd9d300fc364201b9bd8d42d1a5f91160fe8bb025451d95cb4f30cc25be710db8f4cf654f69cbb131e70123ed9c05fadd6326a597ecb20b2"} 15:07:24 INFO - Running post-run listener: copy_logs_to_upload_dir 15:07:24 INFO - Copying logs to upload dir... 15:07:24 INFO - mkdir: /builds/slave/test/build/upload/logs 15:07:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.160661 ========= master_lag: 6.65 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-02-26 15:07:30.764312) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-02-26 15:07:30.771394) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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/2f6edb5345800c3c68f5cdedb9b9714abbe49e3f84b9bbab4e4106bf80db573bffe00b8fcef8c36f80e58d3ec7f08980a67fe4c0d1c5e44bd3a1fbfc160b6076", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f17e87be8e2c5801bd9d300fc364201b9bd8d42d1a5f91160fe8bb025451d95cb4f30cc25be710db8f4cf654f69cbb131e70123ed9c05fadd6326a597ecb20b2"} build_url:https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/eSeHHe_FS6yhk1Y3HMnQ9w/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/2f6edb5345800c3c68f5cdedb9b9714abbe49e3f84b9bbab4e4106bf80db573bffe00b8fcef8c36f80e58d3ec7f08980a67fe4c0d1c5e44bd3a1fbfc160b6076", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f17e87be8e2c5801bd9d300fc364201b9bd8d42d1a5f91160fe8bb025451d95cb4f30cc25be710db8f4cf654f69cbb131e70123ed9c05fadd6326a597ecb20b2"}' ========= master_lag: 11.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-02-26 15:07:42.413956) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-02-26 15:07:42.414359) ========= 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.1AOG8k9zHr/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.wTuAO9Y3KJ/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005378 ========= master_lag: 6.86 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-02-26 15:07:49.279419) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-26 15:07:49.279749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-26 15:07:49.695326) ========= ========= Total master_lag: 54.46 =========