builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0103 starttime: 1456506533.15 results: success (0) buildid: 20160226072527 builduid: ba02eddfd1304293aefd7eba39fff2d7 revision: 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 09:08:53.145982) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 09:08:53.146446) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2016-02-26 09:08:53.146775) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005790 basedir: '/builds/slave/test' ========= master_lag: 1.59 ========= ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2016-02-26 09:08:54.739742) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-02-26 09:08:54.740083) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-02-26 09:09:00.862134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:00.862383) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.006325 ========= master_lag: 0.45 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:01.318065) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:01.318458) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:01.318838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:01.319124) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:09:01-- 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% 14.3M=0.001s 2016-02-26 09:09:01 (14.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191925 ========= master_lag: 0.79 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:02.299553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:02.299890) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.027537 ========= master_lag: 0.29 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:02.617281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-02-26 09:09:02.617735) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 --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 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:09:02,997 truncating revision to first 12 chars 2016-02-26 09:09:02,997 Setting DEBUG logging. 2016-02-26 09:09:02,997 attempt 1/10 2016-02-26 09:09:02,997 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5efb021d57d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-26 09:09:04,242 unpacking tar archive at: fx-team-5efb021d57d9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.493335 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-02-26 09:09:04.781774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:04.782090) ========= echo 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 program finished with exit code 0 elapsedTime=0.005885 script_repo_revision: '5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3' ========= master_lag: 0.08 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-26 09:09:04.872279) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-02-26 09:09:04.872555) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-02-26 09:09:09.199307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-02-26 09:09:09.199783) ========= /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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:09:09 INFO - MultiFileLogger online at 20160226 09:09:09 in /builds/slave/test 09:09:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:09:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:09 INFO - {'append_to_log': False, 09:09:09 INFO - 'base_work_dir': '/builds/slave/test', 09:09:09 INFO - 'blob_upload_branch': 'Fx-Team', 09:09:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:09 INFO - 'branch': 'Fx-Team', 09:09:09 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:09 INFO - 'config_files': ('talos/mac_config.py',), 09:09:09 INFO - 'default_actions': ('clobber', 09:09:09 INFO - 'read-buildbot-config', 09:09:09 INFO - 'download-and-extract', 09:09:09 INFO - 'populate-webroot', 09:09:09 INFO - 'create-virtualenv', 09:09:09 INFO - 'install', 09:09:09 INFO - 'run-tests'), 09:09:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:09 INFO - 'download_minidump_stackwalk': True, 09:09:09 INFO - 'download_symbols': 'ondemand', 09:09:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:09 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:09 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:09 INFO - 'installer_path': 'installer.exe', 09:09:09 INFO - 'log_level': 'info', 09:09:09 INFO - 'log_name': 'talos', 09:09:09 INFO - 'log_to_console': True, 09:09:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:09:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:09:09 INFO - 'opt_config_files': (), 09:09:09 INFO - 'pip_index': False, 09:09:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:09 INFO - 'cmd': ('bash', 09:09:09 INFO - '-c', 09:09:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:09 INFO - 'enabled': True, 09:09:09 INFO - 'halt_on_failure': False, 09:09:09 INFO - 'name': 'check_screen_resolution'},), 09:09:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:09 INFO - 'cmd': ('bash', 09:09:09 INFO - '-c', 09:09:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:09 INFO - 'enabled': True, 09:09:09 INFO - 'halt_on_failure': False, 09:09:09 INFO - 'name': 'check_screen_resolution'},), 09:09:09 INFO - 'run_cmd_checks_enabled': True, 09:09:09 INFO - 'sps_profile': False, 09:09:09 INFO - 'sps_profile_interval': 0, 09:09:09 INFO - 'suite': 'dromaeojs', 09:09:09 INFO - 'system_bits': '32', 09:09:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:09 INFO - 'title': 't-yosemite-r7-0103', 09:09:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:09 INFO - 'use_talos_json': True, 09:09:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:09 INFO - 'work_dir': 'build'} 09:09:09 INFO - ##### 09:09:09 INFO - ##### Running clobber step. 09:09:09 INFO - ##### 09:09:09 INFO - Running pre-action listener: _resource_record_pre_action 09:09:09 INFO - Running main action method: clobber 09:09:09 INFO - rmtree: /builds/slave/test/build 09:09:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:10 INFO - Running post-action listener: _resource_record_post_action 09:09:10 INFO - ##### 09:09:10 INFO - ##### Running read-buildbot-config step. 09:09:10 INFO - ##### 09:09:10 INFO - Running pre-action listener: _resource_record_pre_action 09:09:10 INFO - Running main action method: read_buildbot_config 09:09:10 INFO - Using buildbot properties: 09:09:10 INFO - { 09:09:10 INFO - "project": "", 09:09:10 INFO - "product": "firefox", 09:09:10 INFO - "script_repo_revision": "production", 09:09:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:09:10 INFO - "repository": "", 09:09:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:09:10 INFO - "buildid": "20160226072527", 09:09:10 INFO - "pgo_build": "False", 09:09:10 INFO - "basedir": "/builds/slave/test", 09:09:10 INFO - "buildnumber": 418, 09:09:10 INFO - "slavename": "t-yosemite-r7-0103", 09:09:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:09:10 INFO - "platform": "yosemite_r7", 09:09:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:09:10 INFO - "branch": "fx-team", 09:09:10 INFO - "repo_path": "integration/fx-team", 09:09:10 INFO - "revision": "5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3", 09:09:10 INFO - "stage_platform": "macosx64", 09:09:10 INFO - "builduid": "ba02eddfd1304293aefd7eba39fff2d7", 09:09:10 INFO - "slavebuilddir": "test" 09:09:10 INFO - } 09:09:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 09:09:10 INFO - Running post-action listener: _resource_record_post_action 09:09:10 INFO - ##### 09:09:10 INFO - ##### Running download-and-extract step. 09:09:10 INFO - ##### 09:09:10 INFO - Running pre-action listener: _resource_record_pre_action 09:09:10 INFO - Running main action method: download_and_extract 09:09:10 INFO - mkdir: /builds/slave/test/build/tests 09:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:10 INFO - https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:09:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/test_packages.json 09:09:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/test_packages.json 09:09:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:09:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:09:16 INFO - Downloaded 1277 bytes. 09:09:16 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:09:16 INFO - Using the following test package requirements: 09:09:16 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:09:16 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 09:09:16 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 09:09:16 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'jsshell-mac.zip'], 09:09:16 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 09:09:16 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:09:16 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 09:09:16 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 09:09:16 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 09:09:16 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:09:16 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:09:16 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 09:09:16 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:16 INFO - https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:09:28 INFO - Downloaded 17830412 bytes. 09:09:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:09:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:09:31 INFO - Return code: 0 09:09:31 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:31 INFO - https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:09:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:09:35 INFO - Downloaded 17830412 bytes. 09:09:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:09:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:09:39 INFO - Return code: 0 09:09: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')]} 09:09:39 INFO - https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:09:40 INFO - Downloaded 10713410 bytes. 09:09:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:09:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:09:40 INFO - Return code: 0 09:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:40 INFO - https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:09:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:09:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:09:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 09:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 09:09:47 INFO - Downloaded 111446165 bytes. 09:09:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:09:47 INFO - mkdir: /builds/slave/test/properties 09:09:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:47 INFO - Contents: 09:09:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:09:48 INFO - Running post-action listener: _resource_record_post_action 09:09:48 INFO - Running post-action listener: set_extra_try_arguments 09:09:48 INFO - ##### 09:09:48 INFO - ##### Running populate-webroot step. 09:09:48 INFO - ##### 09:09:48 INFO - Running pre-action listener: _resource_record_pre_action 09:09:48 INFO - Running main action method: populate_webroot 09:09:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:48 INFO - '--tppagecycles', 09:09:48 INFO - '7'], 09:09:48 INFO - 'tests': ['tsvgm']}}, 09:09:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:09:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:09:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'tests': ['damp', 'tps']}, 09:09:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['damp', 'tps']}, 09:09:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:09:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:48 INFO - 'other': {'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'other_l64': {'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'other_nol64': {'tests': ['a11yr', 09:09:48 INFO - 'ts_paint', 09:09:48 INFO - 'tpaint', 09:09:48 INFO - 'sessionrestore', 09:09:48 INFO - 'sessionrestore_no_auto_restore']}, 09:09:48 INFO - 'svgr': {'tests': ['tsvgx', 09:09:48 INFO - 'tsvgr_opacity', 09:09:48 INFO - 'tart', 09:09:48 INFO - 'tscrollx', 09:09:48 INFO - 'cart']}, 09:09:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['tsvgx', 09:09:48 INFO - 'tsvgr_opacity', 09:09:48 INFO - 'tart', 09:09:48 INFO - 'tscrollx', 09:09:48 INFO - 'cart']}, 09:09:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'tests': ['tp5o']}, 09:09:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'talos_options': ['--e10s'], 09:09:48 INFO - 'tests': ['tp5o']}, 09:09:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'talos_options': ['--xperf_path', 09:09:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:48 INFO - 'tests': ['tp5n']}, 09:09:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:48 INFO - 'talos_options': ['--e10s', 09:09:48 INFO - '--xperf_path', 09:09:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:48 INFO - 'tests': ['tp5n']}}, 09:09:48 INFO - 'talos.zip': {'path': '', 09:09:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:48 INFO - Running post-action listener: _resource_record_post_action 09:09:48 INFO - ##### 09:09:48 INFO - ##### Running create-virtualenv step. 09:09:48 INFO - ##### 09:09:48 INFO - Running pre-action listener: _resource_record_pre_action 09:09:48 INFO - Running main action method: create_virtualenv 09:09:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:48 INFO - Using real prefix '/tools/python27' 09:09:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:52 INFO - Installing pip.................done. 09:09:52 INFO - Return code: 0 09:09:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:52 INFO - 'HOME': '/Users/cltbld', 09:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:52 INFO - 'LOGNAME': 'cltbld', 09:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:52 INFO - 'MOZ_NO_REMOTE': '1', 09:09:52 INFO - 'NO_EM_RESTART': '1', 09:09:52 INFO - 'PAGER': '/bin/cat', 09:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:52 INFO - 'PWD': '/builds/slave/test', 09:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:52 INFO - 'SHELL': '/bin/bash', 09:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:09:52 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:52 INFO - 'USER': 'cltbld', 09:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:52 INFO - 'XPC_FLAGS': '0x0', 09:09:52 INFO - 'XPC_SERVICE_NAME': '0', 09:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:52 INFO - Cleaning up... 09:09:52 INFO - Return code: 0 09:09:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:52 INFO - 'HOME': '/Users/cltbld', 09:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:52 INFO - 'LOGNAME': 'cltbld', 09:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:52 INFO - 'MOZ_NO_REMOTE': '1', 09:09:52 INFO - 'NO_EM_RESTART': '1', 09:09:52 INFO - 'PAGER': '/bin/cat', 09:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:52 INFO - 'PWD': '/builds/slave/test', 09:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:52 INFO - 'SHELL': '/bin/bash', 09:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:09:52 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:52 INFO - 'USER': 'cltbld', 09:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:52 INFO - 'XPC_FLAGS': '0x0', 09:09:52 INFO - 'XPC_SERVICE_NAME': '0', 09:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:52 INFO - Downloading/unpacking psutil>=0.7.1 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:55 INFO - Installing collected packages: psutil 09:09:55 INFO - Running setup.py install for psutil 09:09:55 INFO - building 'psutil._psutil_osx' extension 09:09:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:09:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:09:56 INFO - building 'psutil._psutil_posix' extension 09:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:09:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:09:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:09:56 INFO - ^ 09:09:56 INFO - 1 warning generated. 09:09:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:09:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:56 INFO - Successfully installed psutil 09:09:56 INFO - Cleaning up... 09:09:56 INFO - Return code: 0 09:09:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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', '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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:09: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 mozsystemmonitor==0.0.0 09:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:56 INFO - 'HOME': '/Users/cltbld', 09:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:56 INFO - 'LOGNAME': 'cltbld', 09:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:56 INFO - 'MOZ_NO_REMOTE': '1', 09:09:56 INFO - 'NO_EM_RESTART': '1', 09:09:56 INFO - 'PAGER': '/bin/cat', 09:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:56 INFO - 'PWD': '/builds/slave/test', 09:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:56 INFO - 'SHELL': '/bin/bash', 09:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:09:56 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:56 INFO - 'USER': 'cltbld', 09:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:56 INFO - 'XPC_FLAGS': '0x0', 09:09:56 INFO - 'XPC_SERVICE_NAME': '0', 09:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:09:59 INFO - Installing collected packages: mozsystemmonitor 09:09:59 INFO - Running setup.py install for mozsystemmonitor 09:09:59 INFO - Successfully installed mozsystemmonitor 09:09:59 INFO - Cleaning up... 09:09:59 INFO - Return code: 0 09:09:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:59 INFO - 'HOME': '/Users/cltbld', 09:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:59 INFO - 'LOGNAME': 'cltbld', 09:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:59 INFO - 'MOZ_NO_REMOTE': '1', 09:09:59 INFO - 'NO_EM_RESTART': '1', 09:09:59 INFO - 'PAGER': '/bin/cat', 09:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:59 INFO - 'PWD': '/builds/slave/test', 09:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:59 INFO - 'SHELL': '/bin/bash', 09:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:09:59 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:59 INFO - 'USER': 'cltbld', 09:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:59 INFO - 'XPC_FLAGS': '0x0', 09:09:59 INFO - 'XPC_SERVICE_NAME': '0', 09:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:03 INFO - Downloading docopt-0.6.1.tar.gz 09:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:03 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:03 INFO - Running setup.py install for blobuploader 09:10:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:03 INFO - Running setup.py install for requests 09:10:04 INFO - Running setup.py install for docopt 09:10:04 INFO - Successfully installed blobuploader requests docopt 09:10:04 INFO - Cleaning up... 09:10:04 INFO - Return code: 0 09:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:04 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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:04 INFO - 'HOME': '/Users/cltbld', 09:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:04 INFO - 'LOGNAME': 'cltbld', 09:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:04 INFO - 'MOZ_NO_REMOTE': '1', 09:10:04 INFO - 'NO_EM_RESTART': '1', 09:10:04 INFO - 'PAGER': '/bin/cat', 09:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:04 INFO - 'PWD': '/builds/slave/test', 09:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:04 INFO - 'SHELL': '/bin/bash', 09:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:04 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:04 INFO - 'USER': 'cltbld', 09:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:04 INFO - 'XPC_FLAGS': '0x0', 09:10:04 INFO - 'XPC_SERVICE_NAME': '0', 09:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:04 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-A3AYtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:04 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-nXRxoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:04 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-hTphqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-gPN6cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-usko_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iD8pgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-m0YulC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Sz7D0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-6xYppV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-cJecFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-L7EIee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:05 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-LKy6xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-j0G3EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-YeA0pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-dSr8wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-sgLheu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:06 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-cTnsUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:06 INFO - Running setup.py install for manifestparser 09:10:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:06 INFO - Running setup.py install for mozcrash 09:10:06 INFO - Running setup.py install for mozdebug 09:10:06 INFO - Running setup.py install for mozdevice 09:10:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Running setup.py install for mozfile 09:10:07 INFO - Running setup.py install for mozhttpd 09:10:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Running setup.py install for mozinfo 09:10:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Running setup.py install for mozInstall 09:10:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:07 INFO - Running setup.py install for mozleak 09:10:07 INFO - Running setup.py install for mozlog 09:10:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Running setup.py install for moznetwork 09:10:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Running setup.py install for mozprocess 09:10:08 INFO - Running setup.py install for mozprofile 09:10:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Running setup.py install for mozrunner 09:10:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:08 INFO - Running setup.py install for mozscreenshot 09:10:08 INFO - Running setup.py install for moztest 09:10:08 INFO - Running setup.py install for mozversion 09:10:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:09 INFO - Cleaning up... 09:10:09 INFO - Return code: 0 09:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:09 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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:09 INFO - 'HOME': '/Users/cltbld', 09:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:09 INFO - 'LOGNAME': 'cltbld', 09:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:09 INFO - 'MOZ_NO_REMOTE': '1', 09:10:09 INFO - 'NO_EM_RESTART': '1', 09:10:09 INFO - 'PAGER': '/bin/cat', 09:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:09 INFO - 'PWD': '/builds/slave/test', 09:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:09 INFO - 'SHELL': '/bin/bash', 09:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:09 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:09 INFO - 'USER': 'cltbld', 09:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:09 INFO - 'XPC_FLAGS': '0x0', 09:10:09 INFO - 'XPC_SERVICE_NAME': '0', 09:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ljkZWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jphjmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iPjMYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Tefyjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-sKWqE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-D5cFcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:09 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-FPqpxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7XZUAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5v4Iqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-IMV6Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kd2IKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-aAFCr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-zOeasE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-v5Luxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-TV7_6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:10 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-vwyqt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:11 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-mO288P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:13 INFO - Downloading blessings-1.6.tar.gz 09:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:13 INFO - Installing collected packages: blessings 09:10:13 INFO - Running setup.py install for blessings 09:10:13 INFO - Successfully installed blessings 09:10:13 INFO - Cleaning up... 09:10:13 INFO - Return code: 0 09:10:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:14 INFO - Reading from file tmpfile_stdout 09:10:14 INFO - Current package versions: 09:10:14 INFO - blessings == 1.6 09:10:14 INFO - blobuploader == 1.2.4 09:10:14 INFO - docopt == 0.6.1 09:10:14 INFO - manifestparser == 1.1 09:10:14 INFO - mozInstall == 1.12 09:10:14 INFO - mozcrash == 0.16 09:10:14 INFO - mozdebug == 0.1 09:10:14 INFO - mozdevice == 0.48 09:10:14 INFO - mozfile == 1.2 09:10:14 INFO - mozhttpd == 0.7 09:10:14 INFO - mozinfo == 0.9 09:10:14 INFO - mozleak == 0.1 09:10:14 INFO - mozlog == 3.1 09:10:14 INFO - moznetwork == 0.27 09:10:14 INFO - mozprocess == 0.22 09:10:14 INFO - mozprofile == 0.28 09:10:14 INFO - mozrunner == 6.11 09:10:14 INFO - mozscreenshot == 0.1 09:10:14 INFO - mozsystemmonitor == 0.0 09:10:14 INFO - moztest == 0.7 09:10:14 INFO - mozversion == 1.4 09:10:14 INFO - psutil == 3.1.1 09:10:14 INFO - requests == 1.2.3 09:10:14 INFO - wsgiref == 0.1.2 09:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10: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')]} 09:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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')]} 09:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:14 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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:14 INFO - 'HOME': '/Users/cltbld', 09:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:14 INFO - 'LOGNAME': 'cltbld', 09:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:14 INFO - 'MOZ_NO_REMOTE': '1', 09:10:14 INFO - 'NO_EM_RESTART': '1', 09:10:14 INFO - 'PAGER': '/bin/cat', 09:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:14 INFO - 'PWD': '/builds/slave/test', 09:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:14 INFO - 'SHELL': '/bin/bash', 09:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:14 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:14 INFO - 'USER': 'cltbld', 09:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:14 INFO - 'XPC_FLAGS': '0x0', 09:10:14 INFO - 'XPC_SERVICE_NAME': '0', 09:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:14 INFO - Cleaning up... 09:10:14 INFO - Return code: 0 09:10:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10: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')]} 09:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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')]} 09:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:14 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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:14 INFO - 'HOME': '/Users/cltbld', 09:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:14 INFO - 'LOGNAME': 'cltbld', 09:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:14 INFO - 'MOZ_NO_REMOTE': '1', 09:10:14 INFO - 'NO_EM_RESTART': '1', 09:10:14 INFO - 'PAGER': '/bin/cat', 09:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:14 INFO - 'PWD': '/builds/slave/test', 09:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:14 INFO - 'SHELL': '/bin/bash', 09:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:14 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:14 INFO - 'USER': 'cltbld', 09:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:14 INFO - 'XPC_FLAGS': '0x0', 09:10:14 INFO - 'XPC_SERVICE_NAME': '0', 09:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:15 INFO - Downloading/unpacking jsonschema 09:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:17 INFO - Downloading/unpacking functools32 (from jsonschema) 09:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:18 INFO - warning: no files found matching '*.txt' 09:10:18 INFO - no previously-included directories found matching 'build' 09:10:18 INFO - no previously-included directories found matching 'dist' 09:10:18 INFO - no previously-included directories found matching '.git*' 09:10:18 INFO - Installing collected packages: jsonschema, functools32 09:10:18 INFO - Running setup.py install for functools32 09:10:18 INFO - warning: no files found matching '*.txt' 09:10:18 INFO - no previously-included directories found matching 'build' 09:10:18 INFO - no previously-included directories found matching 'dist' 09:10:18 INFO - no previously-included directories found matching '.git*' 09:10:18 INFO - Successfully installed jsonschema functools32 09:10:18 INFO - Cleaning up... 09:10:18 INFO - Return code: 0 09:10:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:18 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 0x10de0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de164c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351462150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, '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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:18 INFO - 'HOME': '/Users/cltbld', 09:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:18 INFO - 'LOGNAME': 'cltbld', 09:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:18 INFO - 'MOZ_NO_REMOTE': '1', 09:10:18 INFO - 'NO_EM_RESTART': '1', 09:10:18 INFO - 'PAGER': '/bin/cat', 09:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:18 INFO - 'PWD': '/builds/slave/test', 09:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:18 INFO - 'SHELL': '/bin/bash', 09:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:18 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:18 INFO - 'USER': 'cltbld', 09:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:18 INFO - 'XPC_FLAGS': '0x0', 09:10:18 INFO - 'XPC_SERVICE_NAME': '0', 09:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:18 INFO - Downloading/unpacking flake8 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:10:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:10:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:10:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:10:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:10:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:10:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:10:22 INFO - no previously-included directories found matching 'docs/_build' 09:10:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:10:22 INFO - Running setup.py install for pep8 09:10:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:10:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:10:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:10:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:10:22 INFO - no previously-included directories found matching 'docs/_build' 09:10:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:10:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:10:22 INFO - Cleaning up... 09:10:22 INFO - Return code: 0 09:10:22 INFO - Running post-action listener: _resource_record_post_action 09:10:22 INFO - Running post-action listener: _start_resource_monitoring 09:10:22 INFO - Starting resource monitoring. 09:10:22 INFO - ##### 09:10:22 INFO - ##### Running install step. 09:10:22 INFO - ##### 09:10:22 INFO - Running pre-action listener: _resource_record_pre_action 09:10:22 INFO - Running main action method: install 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:22 INFO - Reading from file tmpfile_stdout 09:10:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:22 INFO - Reading from file tmpfile_stdout 09:10:22 INFO - Output received: 09:10:22 INFO - Usage: mozinstall [options] installer 09:10:22 INFO - Options: 09:10:22 INFO - -h, --help show this help message and exit 09:10:22 INFO - -d DEST, --destination=DEST 09:10:22 INFO - Directory to install application into. [default: 09:10:22 INFO - "/builds/slave/test"] 09:10:22 INFO - --app=APP Application being installed. [default: firefox] 09:10:22 INFO - mkdir: /builds/slave/test/build/application 09:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:10:56 INFO - Reading from file tmpfile_stdout 09:10:56 INFO - Output received: 09:10:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:10:56 INFO - Running post-action listener: _resource_record_post_action 09:10:56 INFO - ##### 09:10:56 INFO - ##### Running run-tests step. 09:10:56 INFO - ##### 09:10:56 INFO - Running pre-action listener: _resource_record_pre_action 09:10:56 INFO - Running main action method: run_tests 09:10:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:56 INFO - 2016-02-26 09:10:56.925 screenresolution[1661:8107] starting screenresolution argv=screenresolution get 09:10:56 INFO - 2016-02-26 09:10:56.928 screenresolution[1661:8107] Display 0: 1600x1200x32@60 09:10:56 INFO - 2016-02-26 09:10:56.933 screenresolution[1662:8109] starting screenresolution argv=screenresolution list 09:10:56 INFO - Available Modes on Display 0 09:10:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:57 INFO - Intel Iris: 09:10:57 INFO - Chipset Model: Intel Iris 09:10:57 INFO - Type: GPU 09:10:57 INFO - Bus: Built-In 09:10:57 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:57 INFO - Vendor: Intel (0x8086) 09:10:57 INFO - Device ID: 0x0a2e 09:10:57 INFO - Revision ID: 0x0009 09:10:57 INFO - Displays: 09:10:57 INFO - Display: 09:10:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:57 INFO - Display Serial Number: 1600x1200 60 09:10:57 INFO - Main Display: Yes 09:10:57 INFO - Mirror: Off 09:10:57 INFO - Online: Yes 09:10:57 INFO - Rotation: Supported 09:10:57 INFO - Return code: 0 09:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:57 INFO - Python 2.7.3 09:10:57 INFO - Return code: 0 09:10:57 INFO - grabbing minidump binary from tooltool 09:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:57 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 0x10dcb2d30>, '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 0x10dad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:10:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:10:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:57 INFO - Return code: 0 09:10:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:10:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:10:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:10:58 INFO - Return code: 0 09:10:58 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/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:10:58 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/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8CNA4Ix3fB/Render', 09:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:58 INFO - 'HOME': '/Users/cltbld', 09:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:58 INFO - 'LOGNAME': 'cltbld', 09:10:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:58 INFO - 'MOZ_NO_REMOTE': '1', 09:10:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:58 INFO - 'NO_EM_RESTART': '1', 09:10:58 INFO - 'PAGER': '/bin/cat', 09:10:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:58 INFO - 'PWD': '/builds/slave/test', 09:10:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:58 INFO - 'SHELL': '/bin/bash', 09:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JBkWVk4ITb/Listeners', 09:10:58 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:58 INFO - 'USER': 'cltbld', 09:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:58 INFO - 'XPC_FLAGS': '0x0', 09:10:58 INFO - 'XPC_SERVICE_NAME': '0', 09:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:58 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/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:10:58 INFO - mozversion application_buildid: 20160226072527 09:10:58 INFO - mozversion application_changeset: 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 09:10:58 INFO - mozversion application_display_name: Nightly 09:10:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:58 INFO - mozversion application_name: Firefox 09:10:58 INFO - mozversion application_remotingname: firefox 09:10:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:10:58 INFO - mozversion application_vendor: Mozilla 09:10:58 INFO - mozversion application_version: 47.0a1 09:10:58 INFO - mozversion platform_buildid: 20160226072527 09:10:58 INFO - mozversion platform_changeset: 5efb021d57d9b1dcd8a922eff2dbea4d886b8bb3 09:10:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:10:58 INFO - mozversion platform_version: 47.0a1 09:10:58 INFO - using testdate: 1456506658 09:10:58 INFO - actual date: 1456506658 09:10:59 INFO - starting webserver on 'localhost:49280' 09:10:59 INFO - SUITE-START | Running 2 tests 09:10:59 INFO - TEST-START | dromaeo_css 09:10:59 INFO - operating with platform_type : mac_ 09:10:59 INFO - Initialising browser for dromaeo_css test... 09:10:59 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp3yaBYE/profile http://localhost:49280/getInfo.html) 09:11:01 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 09:11:01 INFO - PROCESS | 1680 | colorDepth:24 09:11:01 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 09:11:01 INFO - PROCESS | 1680 | __metrics 09:11:01 INFO - PROCESS | 1680 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:11:01 INFO - PROCESS | 1680 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:11:02 INFO - TEST-INFO | 1680: exit 0 09:11:02 INFO - Browser initialized. 09:11:02 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:02 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp3yaBYE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:14 INFO - PROCESS | 1684 | RSS: Main: 244436992 09:11:14 INFO - PROCESS | 1684 | 09:12:04 INFO - PROCESS | 1684 | 1456506724904 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:14:15 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 09:14:16 INFO - PROCESS | 1684 | RSS: Main: 344989696 09:14:16 INFO - PROCESS | 1684 | 09:17:18 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 09:17:19 INFO - PROCESS | 1684 | RSS: Main: 382898176 09:17:19 INFO - PROCESS | 1684 | 09:20:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 09:20:21 INFO - PROCESS | 1684 | RSS: Main: 379510784 09:20:21 INFO - PROCESS | 1684 | 09:23:23 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 09:23:24 INFO - PROCESS | 1684 | RSS: Main: 340602880 09:23:24 INFO - PROCESS | 1684 | 09:26:27 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 09:26:28 INFO - PROCESS | 1684 | RSS: Main: 340275200 09:26:28 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 09:29:31 INFO - PROCESS | 1684 | RSS: Main: 383631360 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | __start_tp_report 09:29:31 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 09:29:31 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 09:29:31 INFO - PROCESS | 1684 | |i|pagename|runs| 09:29:31 INFO - PROCESS | 1684 | |0;dojo.html;3374.8862875251166;3429.81136037518;3443.2769118485126;3472.4096903526397;3498.42276024456;5458.563314934806;5535.750891209896;5549.2231087647715;5564.10974244121;5573.136163894597;5535.833924982247;5536.975659502995;5578.219049333096;5693.629914055584;5700.344460387057;4939.185034469308;5020.39755229373;5040.1935690289565;5047.318611987372;5215.1655735082395;5099.515546023122;5148.742562871841;5170.689758614476;5172.146595811687;5179.352580927384;4954;4979.90040199194;5000.699958002531;5033.748312584356;5034.09386310464;5060.949390506122;5069.949300507022;5077.1876499759865;5100.566451851597;5105.668131571436;4983.401991760976;5035;5038.622103342264;5057.115004874169;5057.595392368638;5366.114591092465;5435.701036442994;5506.724663766796;5528.723563821793;5554.861128471821;4446.466424029105;6056.242969628796;6096.817095487142;6112.938870611326;6113.32753397126;5307.814226502054;6142.815715528542;6154.661493617849;6164.137020817089;6167.198264225622;4197.034682023122;5148.922766158511;5525.723713814334;5526.751296191681;5637.887242255132;6232.875342493216;6256.468200203034;6271.965125754199;6286.654234017173;6289.056641503811;4932.383452068491;5856.795012174553;5880.088586269135;5957.672328022;5962.642241465526;6036.818895433056;6123.846903827351;6141.877162456815;6143.32423433422;6159.3224745277985;5964.612300200473;5988.730507127189;5996.700164991733;6001.36985616504;6026.156338112668;4952.232403977389;6026.969865150646;6039;6039.335673075958;6047.244094488189;4785.473597904228;4785.9760701196965;4786.880327991828;4796.352492413549;4802.159622066124;4285.442892423965;4307.763073031013;4309.504406993173;4354.738715677069;4380.299152135707;5555.722213889289;5626.409227031185;5629.352624448159;5654.547636189177;5659.688717120515;6096.329403765582;6186.907196392058;6200.3179650238435;6217.533684973645;6219.533534984804;5576.9163462548095;5581.74882130297;5590.357108932443;5604.663720176803;5607.299087614048;5165.767540460688;5212.218167274863;5216.556592689664;5236.083685355124;5283.656562323436;6316.652584107918;6330.746770129143;6336.683165841598;6338.429541341209;6342.968285158637;5039;6175.258968923578;6176.104464852661;6177.382261773966;6183.474404675653;6182.443580077815;6200.596961197508;6253.656048917172;6295.150154729053;6327.90508142369;6111.266648002268;6115;6116.17431646722;6123.7244324006415;6138.416850399205;6164.445199932027;6191.349908259568;6209.751609935552;6216.129741836056;6231.594946328474;5769.9711501442225;5864.354920958776;5955.076963070731;5976.731047102978;6006.939930600638;4647.697899636512;5751.9137212941005;5840.912386314124;5848.2689663792025;5907.4683278505145;5724.112762521816;5739.253896993364;5754;5760.0783874579865;5770.538356931519;5829.59192856496;5881.029630110984;5933.495652869555;5953.345132035386;5992.760289588542;5791.536677065909;5803.158542011469;5803.941960580509;5806.65160090396;5807.157962095557 09:29:31 INFO - PROCESS | 1684 | |1;ext.html;466.6290299212126;485.2696691479323;487.05025200858336;571.4542611805726;620.6989610039132;28059.719402805993;37022.629773702254;37714;37814.516895050205;37970.8101459493;35229.94310170699;40765;40996;41696.374554381706;41803.79098104513;9505.667301644444;9742.951285243564;9753.609855605768;9759.170470510015;9766.706998790047;8142.674293028272;9465.858012129824;9670.709878703616;9675.080867317594;9777.31558790885;9699.70900872975;9761.755956101084;9777.168940640055;9784.706458806248;9787.265955053364;9755.219582433388;9784.90215097847;9786.755331116708;9791.216702663769;9797.706068817912;9570.234381249553;9706.854397184048;9708.660196893146;9719.514024298827;9735.90264097357;39662;40067.7996610018;40129.59870401317;40130.799346003376;40136.39795403071;3086.9536956945662;3154.8422578870964;3172.74618030557;3531.364354416204;3685.594584595692;28753.56869646957;29152.854235728897;29165;29447;29943.251418714488;2760.213339198319;2770.2104900103373;2778.7776977841722;2786.986065069682;2823.2095013396283;454467;456637;457309;457401;458404;44997.32504012442;45561.7721911395;45613;64016;65786.34213657802;44517.219311227185;44521;44611;44717.32924006142;44779.328310075376;15099.24503774829;15270.847291527165;15284.694306113815;15285.923570382078;15319.923400382926;12217.694557635952;12282.938585307016;12288.815667764991;12294.938525307316;12309.384530773426;14609.977301588786;14649.194294313702;14664.120152790867;14680.779788303184;14799.630009249855;6152.846178845475;6270.655113968775;6278.30938596754;6319.557630965788;6322.683865806691;947.6920000999703;969.3117886300805;1008.6570566007592;1054.9683509494728;1105.3644154611131;28816.855915720287;28847;29487;29497.85251073774;29514.409711806074;1022.4734261855151;1080.2276372393776;1090.095220966596;1147.3460127727114;1167.3521195736448;12668.746625067448;14705.705885882437;14780.778288325684;14812.70374592502;15020.09879407239;518068;524888;524928;525590.3720481449;526105;51858.22212666813;57734;57807.71096144492;57967.71016144892;58345;1292.4313302147025;1307.019735198601;1410.3582869794218;1411.1885665742238;1441.654003039263;28376.290592735346;28535.571966420935;29099.7090029097;29119.563206551495;29204.415911681222;45365.319520207886;45506.08987820291;45734.08531829278;45738;45895.082098358514;305901;308329;308421.9051523288;309045;309619;31636.683633163375;33200;33325.33349332951;33354.83322583372;33369.33261334808;43153.56846431621;43665.56334436615;43669.56330436655;44031.55968440403;45028;36390;36482;36628.81685591555;37147.81426092852;37285.4407183887;35949;35961.640383595826;36322.66156313901;36414;36717.816410917774;32790.672093278765;34255.65744342525;35825.82087089548;37179.25641487209;37439;28473.861045558773;30581.847090764404;31743.523847142773;32368;32785.34429311448;21115.86790552758;21343.135247436563;23052.135916519248;24650.753492464846;24970.875145624883 09:29:31 INFO - PROCESS | 1684 | |2;jquery.html;352.68610936266725;355.8754435947418;355.88255875561066;362.0712871484642;362.22122436760947;19120.33078842241;19174.233030678774;20072.799272007276;20141.99999999998;20148.294809681665;20024.399268021945;20654.48363790906;20666.27668031619;20695.793042069574;21100.261490847824;6442.903356449657;6619.966900165517;6672.799816005528;6675.766348177791;6677.766278180265;4498.595126438619;6609.636469994149;7253.310935461122;7558.508696934709;7576.886346704804;7256.4920455568135;7531.397488200931;7542.962285188594;7566.735164269252;7569.583672897988;7419.888701669479;7500.099988001421;7522.022137122167;7525.548467091992;7526.473146879721;4384.978075109636;6846.965765171142;7133.072700548947;7135.857282854366;7143.857122857566;26370;26446;26588;26714.73285267142;26753.33116672099;1930.3147382679088;2167.642339014061;2210.8061646710757;2383.9192187973154;2621.2398404462683;13806.447742090304;14282.285885705673;14337.569872903725;15091.849081509154;15815.683686326323;1843.419322913278;1892.1201641236892;1894.4411398637435;1945.0566475259488;1954.2378472395776;122828;124892.37553812173;126792;126975;128803;15842.44551440694;17350.47948561545;18629.90685046566;18949.62100757977;19418.320358787656;25577.360565985626;28746.99385521497;28809.99165029214;28817;28821.135365939055;10381.2214083944;10560.630377936735;10570.630027948984;10606.15150787935;10704.090152336983;8964.282857371394;8973.910260897437;8986.326025548111;9116.726498205064;9117.042710515301;7093.320472630818;8555.743327700178;8632.35257355701;8707.912920870836;8715.564221788885;3267.787593806395;3405.591329040531;3437.845296961643;3468.809215493172;3592.2456284180616;848.4485084694998;892.8125093730259;977.5552123783662;988.5353883674661;989.3964681544252;19114.71327930082;19972.90013549952;20077;20078.49803754918;20538.281160159324;857.652650676477;935.2891802230218;936.4007035497287;938.6010945348336;982.6855406270068;10658.147348212124;10743.475283308417;10757;10761.354318740743;10841.620543280947;616182;617951;622686;633596;635747;118909;120421;121101;121508;125523.11715324277;970.8932017478072;977.7653363192783;987.0869445762785;993.4982833669095;995.5470261031213;14844.480443184437;17738;17781.46655600334;17980.550486237426;18024.639507210046;49428;49522.50477495179;49703.50296497133;50007;50515.49484505255;56455;73030;73665;76772.61613691896;76949;7453.776386708408;7890.250426209501;7928.445008849325;8005;8256.380771442045;9433.858492122486;10971.396573188233;12874.935625321814;13132.34338283063;13307.201567905957;11165.330080195214;11227.326360418401;11299;11417.828732568853;11491;11023.338599684044;11067.169962253025;11068;11269.154813389192;11329.773404531697;10593.834678185547;10614.628488002883;11315.490802914053;11642;11949.163558550817;6802.625855577793;7928.286454219148;8049.839003219786;8160.755177344689;8211.260986511243;11622.012128969132;11626.360550169822;11835.940820295844;11919.284842909454;11979.525937478034 09:29:31 INFO - PROCESS | 1684 | |3;mootools.html;1374.2304309586639;1498.6852760920206;1528.335174199223;1757.947261582153;1934.516370907273;7837.0987336456255;8444.197801208884;8590.355723320745;8593.398462107663;8628.309735221183;8325.167483251671;8606.483610983345;8608.440451370656;8613.569321533929;8617.224449799516;1745.738139279112;2371.905123795046;2388.1283331583977;2417.395651087228;2424.527217192648;2452.5462789383932;2460.4709987352717;2462.4705688277018;2463.224084413413;2463.9137630182945;2023.6256292585845;2393.054743376374;2412.107520217522;2424.200013995386;2454.840435371704;2390.187336305647;2431.9513609727796;2443.2059580636187;2445.058652418814;2452;2400.8319417640773;2420.5642984262827;2428.368624157714;2434.6591477193206;2437.5977963636155;8555.614997325136;8631.093735157781;8631.180037896389;8645.221930026264;8654.264387527068;5265.552428043621;5376.274202982587;5393.7572809223675;5418.864528386783;5424.538914192259;3564.518789963374;3618.511500947391;3695.1870588470492;3701.796401197933;3721.1813401051727;976.6484065736329;1041.3855825063174;1058.6453538064757;1068.8610480637544;1074.1728868771002;9669.516524173763;11677.182597218198;11741.178117531776;11753.177277590572;11758.294502329887;4744.857654270378;4812.100358993244;4863.659543831898;4872.074305881871;4886.169351210232;8299.336053115736;9907.455089969977;9914.652987145415;9932.851007234898;9938.850917236246;5355.196720491957;5361.731913404315;5362.812301569426;5405.486478784509;5415.702136382458;4388.144311859221;4481.5070342262325;4485.48416932057;4491.842785502492;4928;3796.981015094907;3822.8661996830533;3902.66827319681;3912.2762288976633;3913.1195481016543;1069.5775168808277;1252.6116903759864;1274.4965738533228;1294.5210272199158;1295.151675652449;1295.838020247469;1300.14840279617;1308.770965081107;1316.3023597493343;1329.7938819482995;7950.483218590889;7986.321162701235;7995;8029.518228906284;8214.8357032859;1176.082655528689;1180.8287798269203;1182.1665725663386;1199.9880001199876;1221.7257749231967;5239.973800130975;6210.968945155336;6223.782167624202;6282.528810339243;6307.432331090132;10154.898451015395;10566.947165264124;10587.523561439753;10598.894011059945;10614.416207108683;10078.848817267746;10236.795264094826;10409;10559.04968552834;10649.787004259872;8184.263416292443;8314.792130196793;8369.870067540925;8407.03319118297;8443.029051659016;7743.032120984877;7881.251281128284;7885.408594355561;7929.643166057426;8064.314533264738;9086.227670647804;9378.296627752808;9465.810683786423;9558.522073896416;9654.951725241328;9855.802883942137;9935.65225217114;10017.04667589813;10094.596216151273;10096.990300969845;6911.3779759821855;6931.272216417406;6968.930310696828;7017.929820701727;7049.753258635923;6685.966570167118;8100;8160.387970902325;8165.673373065011;8190.180981901762;7215.494915355875;7357.484976051625;7414.740484083031;7420.777376678709;7539.396848252017;7208.098987626547;7211.963940180265;7260.709571617287;7334.156571994289;7347.779566612797;6501.252355978933;7505.249475052669;7738.690452382067;7765.572893490912;7767.572783496962;6931.792046238621;7054.294570542905;7060.823479413056;7065.823354415975;7072.469564782765;6762.763303284361;7009.649517524002;7029.402500787286;7043.647817608997;7084.22073571917 09:29:31 INFO - PROCESS | 1684 | |4;prototype.html;313.1232548863183;321.1073216458247;326.83658170914543;329.67691662171075;330.8957678331327;6504.739810407584;6534.477241820655;6640.169978752662;6649.301823308556;6658.067870498122;5529.723513824313;6236.469900058501;6485.902711459332;6497.512686548516;6502.6748662566915;4574.885627859297;4680.5085466026085;4695.3426520287185;4818.084563932859;4835.758212089399;4789.640776941726;4792.089502994438;4801.447833499157;4813.6389770767155;4831.565159135677;4733.029728905583;4746.074515469485;4760.523947605229;4767;4770.499097594738;4567.703099298552;4628.467726211495;4747.240441529373;4748.121597504438;4752.168370535177;4058.898527536806;4068.9186216275803;4077.2660921034203;4083.2854250506043;4085.142120154771;6218.098375735538;6261.46777523911;6296.464800491964;6319.684015799192;6327.3988971047675;4783.378160839069;5615.747291371898;5620.606557540974;5635.971820140914;5652.321721393418;6658.067870498134;6899.517033807636;6903;6907.689153988083;6921.273266307066;4629.421322334708;4647.86056418308;4652.209124448852;4659.976700116512;4663.463701674317;7298.489105762599;7353.926460735378;7368.631568421558;7376.59844629524;7388.2242364551485;3886;4703.153432382135;4731.006488637356;4737.478877323491;4743.454502732161;4317.633001194871;4435.733855968652;4803.471618122004;4809.687370320918;4815.3499277597775;4596.287575425815;4610.331501932268;5608.074667679869;5617.662940223599;5624.690642014647;6699.564528305645;6977.720891164398;7010.123734533184;7011.544249623758;7013.088298521262;1359.320339830085;1653.5866033491627;1679.6808606364752;1684.3431061885874;1685.4775019743674;1009.2884516415909;1052.5737076484036;1077.1867240233573;1089.591403223791;1114.0586204657052;5940.990031694622;6089.2997305310455;6286.308506064329;6301.936980630227;6411.358864113551;7344.339009489172;7354;7356.301151390609;7364;7367.079115110611;6576.5067619928695;6582.605043697394;6586.934130658728;6594.142761440982;6705.430038446689;3116.5169398743233;3811.6569508744346;3846.6345697158727;3848.5189351331082;3857.672097871656;7315.341619254293;7588.089429268523;7596.924030759622;7607.08714954198;7616.276453736886;7422.703091876372;7485.588292643957;7587.2412758723685;7587.430942679322;7590.734324298623;7635.809104772425;7656.387489000978;7657.34912532441;7663.6168191590195;7670.309672129535;7079.787606371817;7327.413806895543;7360.815979600553;7363.7422690205585;7371.48399612022;7145.963835243757;7208.098987626547;7224.313690199423;7227.024351712452;7228.891566626399;7638.770836874903;7649.005629268159;7664.73173438927;7668.731594394169;7672.539647621161;3944.4674968878835;4456.818942980048;4649.767511624473;4650.930236046394;4664.090502352009;6468.900286951323;6663.46692264608;7003.894941575779;7262.237465066303;7263.854722905407;7008.4042856355745;7037.788866334018;7053.823654408476;7067.045948797052;7067.646617668993;6999.440044796506;7028.437724982092;7036.472264580279;7038.542494737826;7045.682944267418;6852.280510546321;6856;6857.022926702401;6857.862842743018;6863.965680171567;6799;7019.964900175466;7022.754203602849;7046.260142684944;7079.68141433647;6553;6753.966230168818;6798.966005169942;6881.071055407456;6883.177897062181 09:29:31 INFO - PROCESS | 1684 | |5;yui.html;682.2290811383136;732.6593134192601;733.4059411876384;744.9106107267125;757.503834988083;13490.865091349084;13634.659133521667;13652.658683532916;13657.590272291847;13669.316534173302;15362.769558456604;15802.841971580281;15816.367345306175;15816.604584885383;15824.84175158248;835.5989125219883;865.4850364033422;869.1829680100717;872.0625327772638;873.5326600268858;707.1973310292803;716.0869890889106;850.553459433796;868.3877866104413;869.6260607938584;911.0388540090203;913.8446464101119;914.6707185413246;915.1900567997304;916.138829500271;725.5719125715852;903.1690844423114;906.7370462565848;907.346710368537;908.0374802709116;543.0008783837758;543.2557396367002;544.9754761035763;659.235287067;667.8564108716649;14478.71042579153;14508.782368264485;14515.491957781427;14529.200893950829;14540.563783086523;12257.57098501557;12273.140880138393;12331.321777302244;12351.444185011605;12390.31853248071;10215.23385746072;10226.181905447545;10237.283390162616;10311;10567.947160264226;3441.9311613767836;3446.190145315874;3463.8614455421753;3490.9825450872836;3682.2819550187724;85159;85607;85849.57075214646;86381;86599.13400866037;9299.581518831668;9307.720768376825;9455.912570054394;9483.383580067273;9665.951670241744;20906.26828061041;21065.578688426147;21083.945802709804;21087.261945831822;21147.682784758243;7372.889406658905;7608.923910760822;7694.692212311445;7741.922580774233;7957.9654644896955;4066;4091.243120022805;4109.815058322382;4117.300058989979;4199.538050814408;3543.273628906068;3589.3180295743723;3594.730395220369;3594.9281014379567;3596.6223546527763;632.6172665537375;641.201703878674;645.7578408096964;657.4674513643964;672.939435450802;506.45556027270095;599.7511032921376;599.8020653184439;603.6619493083887;609.6860117039728;8198.42611017235;8341.16588341161;8455.985281766227;8528.872066919;8572.099929507312;579.3829571506333;580.257270693513;592.9318128415288;618.9907151392732;622.483338828771;3856.807159642007;3864.768113913174;3896.8051597420017;3992.920141597152;4065.4714887065084;86687;87879.1212087871;88014.55992720123;96149.59626009266;96227.51886240524;49602;69024;71348.64325678442;71910.68535887758;76277.85583216145;62049;62493;62672.05991910125;63291;63595.682021590525;8418.284445821928;8508.319334453105;8517.3580149744;8541.444806087584;8704.129587041245;46794.83012924704;48474.27288590744;49261.76845578889;49656.50343496519;49977.50022499728;64383.678081609294;66424;67407.32592674207;67452.662736686;67741;10763.408012559072;10788.514516846917;10814.29707069038;10988.175886808362;11366.318020918772;12037;12174.87825121773;12321.44553495077;12417.875821241672;12444.937775311066;11389.829152562552;11484.19610627248;11741.06071514294;11782.646520604396;11824.82262766042;9619.422834629944;11334;11357.37534435614;11375.544978201111;11745.647630571097;11264.211505194558;11519.42402879836;11575.536978521102;11599.072074233873;11603.535858565563;9507.382020168667;9636.988116247692;9671.9032809671;9787.706368808947;9928.751781205528;9436.879496089783;9633.614655413705;9651.806963860823;9722.416655000723;9849.753756156153 09:29:31 INFO - PROCESS | 1684 | __end_tp_report 09:29:31 INFO - PROCESS | 1684 | __start_cc_report 09:29:31 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1132 09:29:31 INFO - PROCESS | 1684 | __end_cc_report 09:29:31 INFO - PROCESS | 1684 | __startTimestamp1456507771318__endTimestamp 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | ------- Summary: start ------- 09:29:31 INFO - PROCESS | 1684 | Number of tests: 6 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#0] dojo.html Cycles:165 Average:5571.52 Median:5739.25 stddev:647.64 (11.3%) stddev-sans-first:626.28 09:29:31 INFO - PROCESS | 1684 | Values: 3374.9 3429.8 3443.3 3472.4 3498.4 5458.6 5535.8 5549.2 5564.1 5573.1 5535.8 5537.0 5578.2 5693.6 5700.3 4939.2 5020.4 5040.2 5047.3 5215.2 5099.5 5148.7 5170.7 5172.1 5179.4 4954.0 4979.9 5000.7 5033.7 5034.1 5060.9 5069.9 5077.2 5100.6 5105.7 4983.4 5035.0 5038.6 5057.1 5057.6 5366.1 5435.7 5506.7 5528.7 5554.9 4446.5 6056.2 6096.8 6112.9 6113.3 5307.8 6142.8 6154.7 6164.1 6167.2 4197.0 5148.9 5525.7 5526.8 5637.9 6232.9 6256.5 6272.0 6286.7 6289.1 4932.4 5856.8 5880.1 5957.7 5962.6 6036.8 6123.8 6141.9 6143.3 6159.3 5964.6 5988.7 5996.7 6001.4 6026.2 4952.2 6027.0 6039.0 6039.3 6047.2 4785.5 4786.0 4786.9 4796.4 4802.2 4285.4 4307.8 4309.5 4354.7 4380.3 5555.7 5626.4 5629.4 5654.5 5659.7 6096.3 6186.9 6200.3 6217.5 6219.5 5576.9 5581.7 5590.4 5604.7 5607.3 5165.8 5212.2 5216.6 5236.1 5283.7 6316.7 6330.7 6336.7 6338.4 6343.0 5039.0 6175.3 6176.1 6177.4 6183.5 6182.4 6200.6 6253.7 6295.2 6327.9 6111.3 6115.0 6116.2 6123.7 6138.4 6164.4 6191.3 6209.8 6216.1 6231.6 5770.0 5864.4 5955.1 5976.7 6006.9 4647.7 5751.9 5840.9 5848.3 5907.5 5724.1 5739.3 5754.0 5760.1 5770.5 5829.6 5881.0 5933.5 5953.3 5992.8 5791.5 5803.2 5803.9 5806.7 5807.2 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#1] ext.html Cycles:180 Average:57039.50 Median:20354.11 stddev:116773.99 (573.7%) stddev-sans-first:117024.32 09:29:31 INFO - PROCESS | 1684 | Values: 466.6 485.3 487.1 571.5 620.7 28059.7 37022.6 37714.0 37814.5 37970.8 35229.9 40765.0 40996.0 41696.4 41803.8 9505.7 9743.0 9753.6 9759.2 9766.7 8142.7 9465.9 9670.7 9675.1 9777.3 9699.7 9761.8 9777.2 9784.7 9787.3 9755.2 9784.9 9786.8 9791.2 9797.7 9570.2 9706.9 9708.7 9719.5 9735.9 39662.0 40067.8 40129.6 40130.8 40136.4 3087.0 3154.8 3172.7 3531.4 3685.6 28753.6 29152.9 29165.0 29447.0 29943.3 2760.2 2770.2 2778.8 2787.0 2823.2 454467.0 456637.0 457309.0 457401.0 458404.0 44997.3 45561.8 45613.0 64016.0 65786.3 44517.2 44521.0 44611.0 44717.3 44779.3 15099.2 15270.8 15284.7 15285.9 15319.9 12217.7 12282.9 12288.8 12294.9 12309.4 14610.0 14649.2 14664.1 14680.8 14799.6 6152.8 6270.7 6278.3 6319.6 6322.7 947.7 969.3 1008.7 1055.0 1105.4 28816.9 28847.0 29487.0 29497.9 29514.4 1022.5 1080.2 1090.1 1147.3 1167.4 12668.7 14705.7 14780.8 14812.7 15020.1 518068.0 524888.0 524928.0 525590.4 526105.0 51858.2 57734.0 57807.7 57967.7 58345.0 1292.4 1307.0 1410.4 1411.2 1441.7 28376.3 28535.6 29099.7 29119.6 29204.4 45365.3 45506.1 45734.1 45738.0 45895.1 305901.0 308329.0 308421.9 309045.0 309619.0 31636.7 33200.0 33325.3 33354.8 33369.3 43153.6 43665.6 43669.6 44031.6 45028.0 36390.0 36482.0 36628.8 37147.8 37285.4 35949.0 35961.6 36322.7 36414.0 36717.8 32790.7 34255.7 35825.8 37179.3 37439.0 28473.9 30581.8 31743.5 32368.0 32785.3 21115.9 21343.1 23052.1 24650.8 24970.9 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#2] jquery.html Cycles:180 Average:36570.33 Median:28778.49 stddev:103853.47 (360.9%) stddev-sans-first:104109.20 09:29:31 INFO - PROCESS | 1684 | Values: 352.7 355.9 355.9 362.1 362.2 19120.3 19174.2 20072.8 20142.0 20148.3 20024.4 20654.5 20666.3 20695.8 21100.3 6442.9 6620.0 6672.8 6675.8 6677.8 4498.6 6609.6 7253.3 7558.5 7576.9 7256.5 7531.4 7543.0 7566.7 7569.6 7419.9 7500.1 7522.0 7525.5 7526.5 4385.0 6847.0 7133.1 7135.9 7143.9 26370.0 26446.0 26588.0 26714.7 26753.3 1930.3 2167.6 2210.8 2383.9 2621.2 13806.4 14282.3 14337.6 15091.8 15815.7 1843.4 1892.1 1894.4 1945.1 1954.2 122828.0 124892.4 126792.0 126975.0 128803.0 15842.4 17350.5 18629.9 18949.6 19418.3 25577.4 28747.0 28810.0 28817.0 28821.1 10381.2 10560.6 10570.6 10606.2 10704.1 8964.3 8973.9 8986.3 9116.7 9117.0 7093.3 8555.7 8632.4 8707.9 8715.6 3267.8 3405.6 3437.8 3468.8 3592.2 848.4 892.8 977.6 988.5 989.4 19114.7 19972.9 20077.0 20078.5 20538.3 857.7 935.3 936.4 938.6 982.7 10658.1 10743.5 10757.0 10761.4 10841.6 616182.0 617951.0 622686.0 633596.0 635747.0 118909.0 120421.0 121101.0 121508.0 125523.1 970.9 977.8 987.1 993.5 995.5 14844.5 17738.0 17781.5 17980.6 18024.6 49428.0 49522.5 49703.5 50007.0 50515.5 56455.0 73030.0 73665.0 76772.6 76949.0 7453.8 7890.3 7928.4 8005.0 8256.4 9433.9 10971.4 12874.9 13132.3 13307.2 11165.3 11227.3 11299.0 11417.8 11491.0 11023.3 11067.2 11068.0 11269.2 11329.8 10593.8 10614.6 11315.5 11642.0 11949.2 6802.6 7928.3 8049.8 8160.8 8211.3 11622.0 11626.4 11835.9 11919.3 11979.5 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#3] mootools.html Cycles:180 Average:5948.67 Median:5369.54 stddev:3111.24 (57.9%) stddev-sans-first:3100.97 09:29:31 INFO - PROCESS | 1684 | Values: 1374.2 1498.7 1528.3 1757.9 1934.5 7837.1 8444.2 8590.4 8593.4 8628.3 8325.2 8606.5 8608.4 8613.6 8617.2 1745.7 2371.9 2388.1 2417.4 2424.5 2452.5 2460.5 2462.5 2463.2 2463.9 2023.6 2393.1 2412.1 2424.2 2454.8 2390.2 2432.0 2443.2 2445.1 2452.0 2400.8 2420.6 2428.4 2434.7 2437.6 8555.6 8631.1 8631.2 8645.2 8654.3 5265.6 5376.3 5393.8 5418.9 5424.5 3564.5 3618.5 3695.2 3701.8 3721.2 976.6 1041.4 1058.6 1068.9 1074.2 9669.5 11677.2 11741.2 11753.2 11758.3 4744.9 4812.1 4863.7 4872.1 4886.2 8299.3 9907.5 9914.7 9932.9 9938.9 5355.2 5361.7 5362.8 5405.5 5415.7 4388.1 4481.5 4485.5 4491.8 4928.0 3797.0 3822.9 3902.7 3912.3 3913.1 1069.6 1252.6 1274.5 1294.5 1295.2 1295.8 1300.1 1308.8 1316.3 1329.8 7950.5 7986.3 7995.0 8029.5 8214.8 1176.1 1180.8 1182.2 1200.0 1221.7 5240.0 6211.0 6223.8 6282.5 6307.4 10154.9 10566.9 10587.5 10598.9 10614.4 10078.8 10236.8 10409.0 10559.0 10649.8 8184.3 8314.8 8369.9 8407.0 8443.0 7743.0 7881.3 7885.4 7929.6 8064.3 9086.2 9378.3 9465.8 9558.5 9655.0 9855.8 9935.7 10017.0 10094.6 10097.0 6911.4 6931.3 6968.9 7017.9 7049.8 6686.0 8100.0 8160.4 8165.7 8190.2 7215.5 7357.5 7414.7 7420.8 7539.4 7208.1 7212.0 7260.7 7334.2 7347.8 6501.3 7505.2 7738.7 7765.6 7767.6 6931.8 7054.3 7060.8 7065.8 7072.5 6762.8 7009.6 7029.4 7043.6 7084.2 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#4] prototype.html Cycles:180 Average:5714.98 Median:6503.71 stddev:1855.32 (28.5%) stddev-sans-first:1815.69 09:29:31 INFO - PROCESS | 1684 | Values: 313.1 321.1 326.8 329.7 330.9 6504.7 6534.5 6640.2 6649.3 6658.1 5529.7 6236.5 6485.9 6497.5 6502.7 4574.9 4680.5 4695.3 4818.1 4835.8 4789.6 4792.1 4801.4 4813.6 4831.6 4733.0 4746.1 4760.5 4767.0 4770.5 4567.7 4628.5 4747.2 4748.1 4752.2 4058.9 4068.9 4077.3 4083.3 4085.1 6218.1 6261.5 6296.5 6319.7 6327.4 4783.4 5615.7 5620.6 5636.0 5652.3 6658.1 6899.5 6903.0 6907.7 6921.3 4629.4 4647.9 4652.2 4660.0 4663.5 7298.5 7353.9 7368.6 7376.6 7388.2 3886.0 4703.2 4731.0 4737.5 4743.5 4317.6 4435.7 4803.5 4809.7 4815.3 4596.3 4610.3 5608.1 5617.7 5624.7 6699.6 6977.7 7010.1 7011.5 7013.1 1359.3 1653.6 1679.7 1684.3 1685.5 1009.3 1052.6 1077.2 1089.6 1114.1 5941.0 6089.3 6286.3 6301.9 6411.4 7344.3 7354.0 7356.3 7364.0 7367.1 6576.5 6582.6 6586.9 6594.1 6705.4 3116.5 3811.7 3846.6 3848.5 3857.7 7315.3 7588.1 7596.9 7607.1 7616.3 7422.7 7485.6 7587.2 7587.4 7590.7 7635.8 7656.4 7657.3 7663.6 7670.3 7079.8 7327.4 7360.8 7363.7 7371.5 7146.0 7208.1 7224.3 7227.0 7228.9 7638.8 7649.0 7664.7 7668.7 7672.5 3944.5 4456.8 4649.8 4650.9 4664.1 6468.9 6663.5 7003.9 7262.2 7263.9 7008.4 7037.8 7053.8 7067.0 7067.6 6999.4 7028.4 7036.5 7038.5 7045.7 6852.3 6856.0 6857.0 6857.9 6864.0 6799.0 7020.0 7022.8 7046.3 7079.7 6553.0 6754.0 6799.0 6881.1 6883.2 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - PROCESS | 1684 | [#5] yui.html Cycles:180 Average:17863.37 Median:614.34 stddev:24892.76 (4052.0%) stddev-sans-first:24929.16 09:29:31 INFO - PROCESS | 1684 | Values: 682.2 732.7 733.4 744.9 757.5 13490.9 13634.7 13652.7 13657.6 13669.3 15362.8 15802.8 15816.4 15816.6 15824.8 835.6 865.5 869.2 872.1 873.5 707.2 716.1 850.6 868.4 869.6 911.0 913.8 914.7 915.2 916.1 725.6 903.2 906.7 907.3 908.0 543.0 543.3 545.0 659.2 667.9 14478.7 14508.8 14515.5 14529.2 14540.6 12257.6 12273.1 12331.3 12351.4 12390.3 10215.2 10226.2 10237.3 10311.0 10567.9 3441.9 3446.2 3463.9 3491.0 3682.3 85159.0 85607.0 85849.6 86381.0 86599.1 9299.6 9307.7 9455.9 9483.4 9666.0 20906.3 21065.6 21083.9 21087.3 21147.7 7372.9 7608.9 7694.7 7741.9 7958.0 4066.0 4091.2 4109.8 4117.3 4199.5 3543.3 3589.3 3594.7 3594.9 3596.6 632.6 641.2 645.8 657.5 672.9 506.5 599.8 599.8 603.7 609.7 8198.4 8341.2 8456.0 8528.9 8572.1 579.4 580.3 592.9 619.0 622.5 3856.8 3864.8 3896.8 3992.9 4065.5 86687.0 87879.1 88014.6 96149.6 96227.5 49602.0 69024.0 71348.6 71910.7 76277.9 62049.0 62493.0 62672.1 63291.0 63595.7 8418.3 8508.3 8517.4 8541.4 8704.1 46794.8 48474.3 49261.8 49656.5 49977.5 64383.7 66424.0 67407.3 67452.7 67741.0 10763.4 10788.5 10814.3 10988.2 11366.3 12037.0 12174.9 12321.4 12417.9 12444.9 11389.8 11484.2 11741.1 11782.6 11824.8 9619.4 11334.0 11357.4 11375.5 11745.6 11264.2 11519.4 11575.5 11599.1 11603.5 9507.4 9637.0 9671.9 9787.7 9928.8 9436.9 9633.6 9651.8 9722.4 9849.8 09:29:31 INFO - PROCESS | 1684 | -------- Summary: end -------- 09:29:31 INFO - PROCESS | 1684 | 09:29:31 INFO - TEST-INFO | 1684: exit 0 09:29:31 INFO - TEST-OK | dromaeo_css | took 1112684ms 09:29:31 INFO - TEST-START | kraken 09:29:31 INFO - operating with platform_type : mac_ 09:29:31 INFO - Initialising browser for kraken test... 09:29:31 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpe3_msR/profile http://localhost:49280/getInfo.html) 09:29:34 INFO - PROCESS | 1689 | __metrics Screen width/height:1600/1200 09:29:34 INFO - PROCESS | 1689 | colorDepth:24 09:29:34 INFO - PROCESS | 1689 | Browser inner width/height: 1024/689 09:29:34 INFO - PROCESS | 1689 | __metrics 09:29:34 INFO - PROCESS | 1689 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:29:34 INFO - PROCESS | 1689 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:29:34 INFO - TEST-INFO | 1689: exit 0 09:29:34 INFO - Browser initialized. 09:29:34 INFO - Running cycle 1/1 for kraken test... 09:29:34 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpe3_msR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:46 INFO - PROCESS | 1691 | RSS: Main: 244432896 09:29:46 INFO - PROCESS | 1691 | 09:29:49 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:49 INFO - PROCESS | 1691 | RSS: Main: 290758656 09:29:49 INFO - PROCESS | 1691 | 09:29:52 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 09:29:53 INFO - PROCESS | 1691 | RSS: Main: 300253184 09:29:53 INFO - PROCESS | 1691 | 09:29:56 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 09:29:57 INFO - PROCESS | 1691 | RSS: Main: 284598272 09:29:57 INFO - PROCESS | 1691 | 09:29:58 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 09:29:59 INFO - PROCESS | 1691 | RSS: Main: 286031872 09:29:59 INFO - PROCESS | 1691 | 09:30:01 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:02 INFO - PROCESS | 1691 | RSS: Main: 288120832 09:30:02 INFO - PROCESS | 1691 | 09:30:04 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:05 INFO - PROCESS | 1691 | RSS: Main: 289890304 09:30:05 INFO - PROCESS | 1691 | 09:30:08 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:08 INFO - PROCESS | 1691 | RSS: Main: 288342016 09:30:08 INFO - PROCESS | 1691 | 09:30:11 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 09:30:12 INFO - PROCESS | 1691 | RSS: Main: 289218560 09:30:12 INFO - PROCESS | 1691 | 09:30:13 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:30:14 INFO - PROCESS | 1691 | RSS: Main: 324956160 09:30:14 INFO - PROCESS | 1691 | 09:30:16 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:16 INFO - PROCESS | 1691 | RSS: Main: 323862528 09:30:16 INFO - PROCESS | 1691 | 09:30:18 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:19 INFO - PROCESS | 1691 | RSS: Main: 332623872 09:30:19 INFO - PROCESS | 1691 | 09:30:22 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:22 INFO - PROCESS | 1691 | RSS: Main: 331825152 09:30:22 INFO - PROCESS | 1691 | 09:30:25 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:25 INFO - PROCESS | 1691 | RSS: Main: 333373440 09:30:25 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 09:30:27 INFO - PROCESS | 1691 | RSS: Main: 393904128 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | __start_tp_report 09:30:27 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 09:30:27 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 09:30:27 INFO - PROCESS | 1691 | |i|pagename|runs| 09:30:27 INFO - PROCESS | 1691 | |0;ai-astar;111;111;112;112;110;112;118;111;112;111 09:30:27 INFO - PROCESS | 1691 | |1;audio-beat-detection;159;123;156;192;150;142;141;117;164;105 09:30:27 INFO - PROCESS | 1691 | |2;audio-dft;187;184;185;187;186;187;184;182;183;185 09:30:27 INFO - PROCESS | 1691 | |3;audio-fft;77;78;87;79;58;70;58;78;78;76 09:30:27 INFO - PROCESS | 1691 | |4;audio-oscillator;92;92;131;79;80;90;110;78;78;110 09:30:27 INFO - PROCESS | 1691 | |5;imaging-gaussian-blur;87;91;86;91;85;90;86;92;86;92 09:30:27 INFO - PROCESS | 1691 | |6;imaging-darkroom;95;101;95;95;94;95;95;95;94;95 09:30:27 INFO - PROCESS | 1691 | |7;imaging-desaturate;86;96;139;133;87;85;84;87;88;85 09:30:27 INFO - PROCESS | 1691 | |8;json-parse-financial;57;55;56;56;55;56;55;69;57;54 09:30:27 INFO - PROCESS | 1691 | |9;json-stringify-tinderbox;64;64;64;81;72;71;67;66;68;69 09:30:27 INFO - PROCESS | 1691 | |10;stanford-crypto-aes;95;84;83;84;83;82;82;83;86;84 09:30:27 INFO - PROCESS | 1691 | |11;stanford-crypto-ccm;126;124;121;128;114;130;128;108;130;120 09:30:27 INFO - PROCESS | 1691 | |12;stanford-crypto-pbkdf2;138;125;136;131;136;141;137;138;136;139 09:30:27 INFO - PROCESS | 1691 | |13;stanford-crypto-sha256-iterative;67;68;65;68;96;91;94;96;66;66 09:30:27 INFO - PROCESS | 1691 | __end_tp_report 09:30:27 INFO - PROCESS | 1691 | __start_cc_report 09:30:27 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,1259 09:30:27 INFO - PROCESS | 1691 | __end_cc_report 09:30:27 INFO - PROCESS | 1691 | __startTimestamp1456507827921__endTimestamp 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | ------- Summary: start ------- 09:30:27 INFO - PROCESS | 1691 | Number of tests: 14 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:2.21 (2.0%) stddev-sans-first:2.32 09:30:27 INFO - PROCESS | 1691 | Values: 111.0 111.0 112.0 112.0 110.0 112.0 118.0 111.0 112.0 111.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:153.00 stddev:25.40 (16.6%) stddev-sans-first:26.42 09:30:27 INFO - PROCESS | 1691 | Values: 159.0 123.0 156.0 192.0 150.0 142.0 141.0 117.0 164.0 105.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.76 (1.0%) stddev-sans-first:1.72 09:30:27 INFO - PROCESS | 1691 | Values: 187.0 184.0 185.0 187.0 186.0 187.0 184.0 182.0 183.0 185.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#3] audio-fft Cycles:10 Average:73.90 Median:78.00 stddev:9.33 (12.0%) stddev-sans-first:9.82 09:30:27 INFO - PROCESS | 1691 | Values: 77.0 78.0 87.0 79.0 58.0 70.0 58.0 78.0 78.0 76.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.50 stddev:17.76 (22.3%) stddev-sans-first:18.82 09:30:27 INFO - PROCESS | 1691 | Values: 92.0 92.0 131.0 79.0 80.0 90.0 110.0 78.0 78.0 110.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:90.50 stddev:2.84 (3.1%) stddev-sans-first:2.95 09:30:27 INFO - PROCESS | 1691 | Values: 87.0 91.0 86.0 91.0 85.0 90.0 86.0 92.0 86.0 92.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 09:30:27 INFO - PROCESS | 1691 | Values: 95.0 101.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:86.50 stddev:20.87 (24.1%) stddev-sans-first:21.75 09:30:27 INFO - PROCESS | 1691 | Values: 86.0 96.0 139.0 133.0 87.0 85.0 84.0 87.0 88.0 85.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:56.00 stddev:4.32 (7.7%) stddev-sans-first:4.58 09:30:27 INFO - PROCESS | 1691 | Values: 57.0 55.0 56.0 56.0 55.0 56.0 55.0 69.0 57.0 54.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.50 stddev:5.21 (7.6%) stddev-sans-first:5.25 09:30:27 INFO - PROCESS | 1691 | Values: 64.0 64.0 64.0 81.0 72.0 71.0 67.0 66.0 68.0 69.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.00 stddev:3.84 (4.6%) stddev-sans-first:1.24 09:30:27 INFO - PROCESS | 1691 | Values: 95.0 84.0 83.0 84.0 83.0 82.0 82.0 83.0 86.0 84.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:127.00 stddev:7.28 (5.7%) stddev-sans-first:7.63 09:30:27 INFO - PROCESS | 1691 | Values: 126.0 124.0 121.0 128.0 114.0 130.0 128.0 108.0 130.0 120.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.70 Median:137.50 stddev:4.57 (3.3%) stddev-sans-first:4.77 09:30:27 INFO - PROCESS | 1691 | Values: 138.0 125.0 136.0 131.0 136.0 141.0 137.0 138.0 136.0 139.0 09:30:27 INFO - PROCESS | 1691 | 09:30:27 INFO - PROCESS | 1691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:79.50 stddev:14.34 (18.0%) stddev-sans-first:14.68 09:30:27 INFO - PROCESS | 1691 | Values: 67.0 68.0 65.0 68.0 96.0 91.0 94.0 96.0 66.0 66.0 09:30:27 INFO - PROCESS | 1691 | -------- Summary: end -------- 09:30:27 INFO - PROCESS | 1691 | 09:30:28 INFO - TEST-INFO | 1691: exit 0 09:30:28 INFO - TEST-OK | kraken | took 56802ms 09:30:28 INFO - SUITE-END | took 1169s 09:30:29 INFO - Completed test suite (00:19:29) 09:30:29 INFO - Working with test: dromaeo_css 09:30:29 INFO - Generating results file: dromaeo_css 09:30:29 INFO - Working with test: kraken 09:30:29 INFO - Generating results file: kraken 09:30:29 INFO - javascript benchmark 09:30:29 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:30:29 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 09:30:29 INFO - process_Request line: dromaeo_css 7539.36 graph.html#tests=[[72,64,59]] 09:30:29 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:30:29 INFO - process_Request line: kraken 1437.30 graph.html#tests=[[232,64,59]] 09:30:29 INFO - RETURN: dromaeo_css: 7539.36 09:30:29 INFO - RETURN: kraken: 1437.30 09:30:29 INFO - javascript benchmark 09:30:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3374.8862875251166, 3429.81136037518, 3443.2769118485126, 3472.4096903526397, 3498.42276024456, 5458.563314934806, 5535.750891209896, 5549.2231087647715, 5564.10974244121, 5573.136163894597, 5535.833924982247, 5536.975659502995, 5578.219049333096, 5693.629914055584, 5700.344460387057, 4939.185034469308, 5020.39755229373, 5040.1935690289565, 5047.318611987372, 5215.1655735082395, 5099.515546023122, 5148.742562871841, 5170.689758614476, 5172.146595811687, 5179.352580927384, 4954.0, 4979.90040199194, 5000.699958002531, 5033.748312584356, 5034.09386310464, 5060.949390506122, 5069.949300507022, 5077.1876499759865, 5100.566451851597, 5105.668131571436, 4983.401991760976, 5035.0, 5038.622103342264, 5057.115004874169, 5057.595392368638, 5366.114591092465, 5435.701036442994, 5506.724663766796, 5528.723563821793, 5554.861128471821, 4446.466424029105, 6056.242969628796, 6096.817095487142, 6112.938870611326, 6113.32753397126, 5307.814226502054, 6142.815715528542, 6154.661493617849, 6164.137020817089, 6167.198264225622, 4197.034682023122, 5148.922766158511, 5525.723713814334, 5526.751296191681, 5637.887242255132, 6232.875342493216, 6256.468200203034, 6271.965125754199, 6286.654234017173, 6289.056641503811, 4932.383452068491, 5856.795012174553, 5880.088586269135, 5957.672328022, 5962.642241465526, 6036.818895433056, 6123.846903827351, 6141.877162456815, 6143.32423433422, 6159.3224745277985, 5964.612300200473, 5988.730507127189, 5996.700164991733, 6001.36985616504, 6026.156338112668, 4952.232403977389, 6026.969865150646, 6039.0, 6039.335673075958, 6047.244094488189, 4785.473597904228, 4785.9760701196965, 4786.880327991828, 4796.352492413549, 4802.159622066124, 4285.442892423965, 4307.763073031013, 4309.504406993173, 4354.738715677069, 4380.299152135707, 5555.722213889289, 5626.409227031185, 5629.352624448159, 5654.547636189177, 5659.688717120515, 6096.329403765582, 6186.907196392058, 6200.3179650238435, 6217.533684973645, 6219.533534984804, 5576.9163462548095, 5581.74882130297, 5590.357108932443, 5604.663720176803, 5607.299087614048, 5165.767540460688, 5212.218167274863, 5216.556592689664, 5236.083685355124, 5283.656562323436, 6316.652584107918, 6330.746770129143, 6336.683165841598, 6338.429541341209, 6342.968285158637, 5039.0, 6175.258968923578, 6176.104464852661, 6177.382261773966, 6183.474404675653, 6182.443580077815, 6200.596961197508, 6253.656048917172, 6295.150154729053, 6327.90508142369, 6111.266648002268, 6115.0, 6116.17431646722, 6123.7244324006415, 6138.416850399205, 6164.445199932027, 6191.349908259568, 6209.751609935552, 6216.129741836056, 6231.594946328474, 5769.9711501442225, 5864.354920958776, 5955.076963070731, 5976.731047102978, 6006.939930600638, 4647.697899636512, 5751.9137212941005, 5840.912386314124, 5848.2689663792025, 5907.4683278505145, 5724.112762521816, 5739.253896993364, 5754.0, 5760.0783874579865, 5770.538356931519, 5829.59192856496, 5881.029630110984, 5933.495652869555, 5953.345132035386, 5992.760289588542, 5791.536677065909, 5803.158542011469, 5803.941960580509, 5806.65160090396, 5807.157962095557], "unit": "score", "name": "dojo.html", "value": 5533.8090073707035}, {"lowerIsBetter": false, "replicates": [466.6290299212126, 485.2696691479323, 487.05025200858336, 571.4542611805726, 620.6989610039132, 28059.719402805993, 37022.629773702254, 37714.0, 37814.516895050205, 37970.8101459493, 35229.94310170699, 40765.0, 40996.0, 41696.374554381706, 41803.79098104513, 9505.667301644444, 9742.951285243564, 9753.609855605768, 9759.170470510015, 9766.706998790047, 8142.674293028272, 9465.858012129824, 9670.709878703616, 9675.080867317594, 9777.31558790885, 9699.70900872975, 9761.755956101084, 9777.168940640055, 9784.706458806248, 9787.265955053364, 9755.219582433388, 9784.90215097847, 9786.755331116708, 9791.216702663769, 9797.706068817912, 9570.234381249553, 9706.854397184048, 9708.660196893146, 9719.514024298827, 9735.90264097357, 39662.0, 40067.7996610018, 40129.59870401317, 40130.799346003376, 40136.39795403071, 3086.9536956945662, 3154.8422578870964, 3172.74618030557, 3531.364354416204, 3685.594584595692, 28753.56869646957, 29152.854235728897, 29165.0, 29447.0, 29943.251418714488, 2760.213339198319, 2770.2104900103373, 2778.7776977841722, 2786.986065069682, 2823.2095013396283, 454467.0, 456637.0, 457309.0, 457401.0, 458404.0, 44997.32504012442, 45561.7721911395, 45613.0, 64016.0, 65786.34213657802, 44517.219311227185, 44521.0, 44611.0, 44717.32924006142, 44779.328310075376, 15099.24503774829, 15270.847291527165, 15284.694306113815, 15285.923570382078, 15319.923400382926, 12217.694557635952, 12282.938585307016, 12288.815667764991, 12294.938525307316, 12309.384530773426, 14609.977301588786, 14649.194294313702, 14664.120152790867, 14680.779788303184, 14799.630009249855, 6152.846178845475, 6270.655113968775, 6278.30938596754, 6319.557630965788, 6322.683865806691, 947.6920000999703, 969.3117886300805, 1008.6570566007592, 1054.9683509494728, 1105.3644154611131, 28816.855915720287, 28847.0, 29487.0, 29497.85251073774, 29514.409711806074, 1022.4734261855151, 1080.2276372393776, 1090.095220966596, 1147.3460127727114, 1167.3521195736448, 12668.746625067448, 14705.705885882437, 14780.778288325684, 14812.70374592502, 15020.09879407239, 518068.0, 524888.0, 524928.0, 525590.3720481449, 526105.0, 51858.22212666813, 57734.0, 57807.71096144492, 57967.71016144892, 58345.0, 1292.4313302147025, 1307.019735198601, 1410.3582869794218, 1411.1885665742238, 1441.654003039263, 28376.290592735346, 28535.571966420935, 29099.7090029097, 29119.563206551495, 29204.415911681222, 45365.319520207886, 45506.08987820291, 45734.08531829278, 45738.0, 45895.082098358514, 305901.0, 308329.0, 308421.9051523288, 309045.0, 309619.0, 31636.683633163375, 33200.0, 33325.33349332951, 33354.83322583372, 33369.33261334808, 43153.56846431621, 43665.56334436615, 43669.56330436655, 44031.55968440403, 45028.0, 36390.0, 36482.0, 36628.81685591555, 37147.81426092852, 37285.4407183887, 35949.0, 35961.640383595826, 36322.66156313901, 36414.0, 36717.816410917774, 32790.672093278765, 34255.65744342525, 35825.82087089548, 37179.25641487209, 37439.0, 28473.861045558773, 30581.847090764404, 31743.523847142773, 32368.0, 32785.34429311448, 21115.86790552758, 21343.135247436563, 23052.135916519248, 24650.753492464846, 24970.875145624883], "unit": "score", "name": "ext.html", "value": 18592.298784213384}, {"lowerIsBetter": false, "replicates": [352.68610936266725, 355.8754435947418, 355.88255875561066, 362.0712871484642, 362.22122436760947, 19120.33078842241, 19174.233030678774, 20072.799272007276, 20141.99999999998, 20148.294809681665, 20024.399268021945, 20654.48363790906, 20666.27668031619, 20695.793042069574, 21100.261490847824, 6442.903356449657, 6619.966900165517, 6672.799816005528, 6675.766348177791, 6677.766278180265, 4498.595126438619, 6609.636469994149, 7253.310935461122, 7558.508696934709, 7576.886346704804, 7256.4920455568135, 7531.397488200931, 7542.962285188594, 7566.735164269252, 7569.583672897988, 7419.888701669479, 7500.099988001421, 7522.022137122167, 7525.548467091992, 7526.473146879721, 4384.978075109636, 6846.965765171142, 7133.072700548947, 7135.857282854366, 7143.857122857566, 26370.0, 26446.0, 26588.0, 26714.73285267142, 26753.33116672099, 1930.3147382679088, 2167.642339014061, 2210.8061646710757, 2383.9192187973154, 2621.2398404462683, 13806.447742090304, 14282.285885705673, 14337.569872903725, 15091.849081509154, 15815.683686326323, 1843.419322913278, 1892.1201641236892, 1894.4411398637435, 1945.0566475259488, 1954.2378472395776, 122828.0, 124892.37553812173, 126792.0, 126975.0, 128803.0, 15842.44551440694, 17350.47948561545, 18629.90685046566, 18949.62100757977, 19418.320358787656, 25577.360565985626, 28746.99385521497, 28809.99165029214, 28817.0, 28821.135365939055, 10381.2214083944, 10560.630377936735, 10570.630027948984, 10606.15150787935, 10704.090152336983, 8964.282857371394, 8973.910260897437, 8986.326025548111, 9116.726498205064, 9117.042710515301, 7093.320472630818, 8555.743327700178, 8632.35257355701, 8707.912920870836, 8715.564221788885, 3267.787593806395, 3405.591329040531, 3437.845296961643, 3468.809215493172, 3592.2456284180616, 848.4485084694998, 892.8125093730259, 977.5552123783662, 988.5353883674661, 989.3964681544252, 19114.71327930082, 19972.90013549952, 20077.0, 20078.49803754918, 20538.281160159324, 857.652650676477, 935.2891802230218, 936.4007035497287, 938.6010945348336, 982.6855406270068, 10658.147348212124, 10743.475283308417, 10757.0, 10761.354318740743, 10841.620543280947, 616182.0, 617951.0, 622686.0, 633596.0, 635747.0, 118909.0, 120421.0, 121101.0, 121508.0, 125523.11715324277, 970.8932017478072, 977.7653363192783, 987.0869445762785, 993.4982833669095, 995.5470261031213, 14844.480443184437, 17738.0, 17781.46655600334, 17980.550486237426, 18024.639507210046, 49428.0, 49522.50477495179, 49703.50296497133, 50007.0, 50515.49484505255, 56455.0, 73030.0, 73665.0, 76772.61613691896, 76949.0, 7453.776386708408, 7890.250426209501, 7928.445008849325, 8005.0, 8256.380771442045, 9433.858492122486, 10971.396573188233, 12874.935625321814, 13132.34338283063, 13307.201567905957, 11165.330080195214, 11227.326360418401, 11299.0, 11417.828732568853, 11491.0, 11023.338599684044, 11067.169962253025, 11068.0, 11269.154813389192, 11329.773404531697, 10593.834678185547, 10614.628488002883, 11315.490802914053, 11642.0, 11949.163558550817, 6802.625855577793, 7928.286454219148, 8049.839003219786, 8160.755177344689, 8211.260986511243, 11622.012128969132, 11626.360550169822, 11835.940820295844, 11919.284842909454, 11979.525937478034], "unit": "score", "name": "jquery.html", "value": 10574.300966039189}, {"lowerIsBetter": false, "replicates": [1374.2304309586639, 1498.6852760920206, 1528.335174199223, 1757.947261582153, 1934.516370907273, 7837.0987336456255, 8444.197801208884, 8590.355723320745, 8593.398462107663, 8628.309735221183, 8325.167483251671, 8606.483610983345, 8608.440451370656, 8613.569321533929, 8617.224449799516, 1745.738139279112, 2371.905123795046, 2388.1283331583977, 2417.395651087228, 2424.527217192648, 2452.5462789383932, 2460.4709987352717, 2462.4705688277018, 2463.224084413413, 2463.9137630182945, 2023.6256292585845, 2393.054743376374, 2412.107520217522, 2424.200013995386, 2454.840435371704, 2390.187336305647, 2431.9513609727796, 2443.2059580636187, 2445.058652418814, 2452.0, 2400.8319417640773, 2420.5642984262827, 2428.368624157714, 2434.6591477193206, 2437.5977963636155, 8555.614997325136, 8631.093735157781, 8631.180037896389, 8645.221930026264, 8654.264387527068, 5265.552428043621, 5376.274202982587, 5393.7572809223675, 5418.864528386783, 5424.538914192259, 3564.518789963374, 3618.511500947391, 3695.1870588470492, 3701.796401197933, 3721.1813401051727, 976.6484065736329, 1041.3855825063174, 1058.6453538064757, 1068.8610480637544, 1074.1728868771002, 9669.516524173763, 11677.182597218198, 11741.178117531776, 11753.177277590572, 11758.294502329887, 4744.857654270378, 4812.100358993244, 4863.659543831898, 4872.074305881871, 4886.169351210232, 8299.336053115736, 9907.455089969977, 9914.652987145415, 9932.851007234898, 9938.850917236246, 5355.196720491957, 5361.731913404315, 5362.812301569426, 5405.486478784509, 5415.702136382458, 4388.144311859221, 4481.5070342262325, 4485.48416932057, 4491.842785502492, 4928.0, 3796.981015094907, 3822.8661996830533, 3902.66827319681, 3912.2762288976633, 3913.1195481016543, 1069.5775168808277, 1252.6116903759864, 1274.4965738533228, 1294.5210272199158, 1295.151675652449, 1295.838020247469, 1300.14840279617, 1308.770965081107, 1316.3023597493343, 1329.7938819482995, 7950.483218590889, 7986.321162701235, 7995.0, 8029.518228906284, 8214.8357032859, 1176.082655528689, 1180.8287798269203, 1182.1665725663386, 1199.9880001199876, 1221.7257749231967, 5239.973800130975, 6210.968945155336, 6223.782167624202, 6282.528810339243, 6307.432331090132, 10154.898451015395, 10566.947165264124, 10587.523561439753, 10598.894011059945, 10614.416207108683, 10078.848817267746, 10236.795264094826, 10409.0, 10559.04968552834, 10649.787004259872, 8184.263416292443, 8314.792130196793, 8369.870067540925, 8407.03319118297, 8443.029051659016, 7743.032120984877, 7881.251281128284, 7885.408594355561, 7929.643166057426, 8064.314533264738, 9086.227670647804, 9378.296627752808, 9465.810683786423, 9558.522073896416, 9654.951725241328, 9855.802883942137, 9935.65225217114, 10017.04667589813, 10094.596216151273, 10096.990300969845, 6911.3779759821855, 6931.272216417406, 6968.930310696828, 7017.929820701727, 7049.753258635923, 6685.966570167118, 8100.0, 8160.387970902325, 8165.673373065011, 8190.180981901762, 7215.494915355875, 7357.484976051625, 7414.740484083031, 7420.777376678709, 7539.396848252017, 7208.098987626547, 7211.963940180265, 7260.709571617287, 7334.156571994289, 7347.779566612797, 6501.252355978933, 7505.249475052669, 7738.690452382067, 7765.572893490912, 7767.572783496962, 6931.792046238621, 7054.294570542905, 7060.823479413056, 7065.823354415975, 7072.469564782765, 6762.763303284361, 7009.649517524002, 7029.402500787286, 7043.647817608997, 7084.22073571917], "unit": "score", "name": "mootools.html", "value": 4866.079920698798}, {"lowerIsBetter": false, "replicates": [313.1232548863183, 321.1073216458247, 326.83658170914543, 329.67691662171075, 330.8957678331327, 6504.739810407584, 6534.477241820655, 6640.169978752662, 6649.301823308556, 6658.067870498122, 5529.723513824313, 6236.469900058501, 6485.902711459332, 6497.512686548516, 6502.6748662566915, 4574.885627859297, 4680.5085466026085, 4695.3426520287185, 4818.084563932859, 4835.758212089399, 4789.640776941726, 4792.089502994438, 4801.447833499157, 4813.6389770767155, 4831.565159135677, 4733.029728905583, 4746.074515469485, 4760.523947605229, 4767.0, 4770.499097594738, 4567.703099298552, 4628.467726211495, 4747.240441529373, 4748.121597504438, 4752.168370535177, 4058.898527536806, 4068.9186216275803, 4077.2660921034203, 4083.2854250506043, 4085.142120154771, 6218.098375735538, 6261.46777523911, 6296.464800491964, 6319.684015799192, 6327.3988971047675, 4783.378160839069, 5615.747291371898, 5620.606557540974, 5635.971820140914, 5652.321721393418, 6658.067870498134, 6899.517033807636, 6903.0, 6907.689153988083, 6921.273266307066, 4629.421322334708, 4647.86056418308, 4652.209124448852, 4659.976700116512, 4663.463701674317, 7298.489105762599, 7353.926460735378, 7368.631568421558, 7376.59844629524, 7388.2242364551485, 3886.0, 4703.153432382135, 4731.006488637356, 4737.478877323491, 4743.454502732161, 4317.633001194871, 4435.733855968652, 4803.471618122004, 4809.687370320918, 4815.3499277597775, 4596.287575425815, 4610.331501932268, 5608.074667679869, 5617.662940223599, 5624.690642014647, 6699.564528305645, 6977.720891164398, 7010.123734533184, 7011.544249623758, 7013.088298521262, 1359.320339830085, 1653.5866033491627, 1679.6808606364752, 1684.3431061885874, 1685.4775019743674, 1009.2884516415909, 1052.5737076484036, 1077.1867240233573, 1089.591403223791, 1114.0586204657052, 5940.990031694622, 6089.2997305310455, 6286.308506064329, 6301.936980630227, 6411.358864113551, 7344.339009489172, 7354.0, 7356.301151390609, 7364.0, 7367.079115110611, 6576.5067619928695, 6582.605043697394, 6586.934130658728, 6594.142761440982, 6705.430038446689, 3116.5169398743233, 3811.6569508744346, 3846.6345697158727, 3848.5189351331082, 3857.672097871656, 7315.341619254293, 7588.089429268523, 7596.924030759622, 7607.08714954198, 7616.276453736886, 7422.703091876372, 7485.588292643957, 7587.2412758723685, 7587.430942679322, 7590.734324298623, 7635.809104772425, 7656.387489000978, 7657.34912532441, 7663.6168191590195, 7670.309672129535, 7079.787606371817, 7327.413806895543, 7360.815979600553, 7363.7422690205585, 7371.48399612022, 7145.963835243757, 7208.098987626547, 7224.313690199423, 7227.024351712452, 7228.891566626399, 7638.770836874903, 7649.005629268159, 7664.73173438927, 7668.731594394169, 7672.539647621161, 3944.4674968878835, 4456.818942980048, 4649.767511624473, 4650.930236046394, 4664.090502352009, 6468.900286951323, 6663.46692264608, 7003.894941575779, 7262.237465066303, 7263.854722905407, 7008.4042856355745, 7037.788866334018, 7053.823654408476, 7067.045948797052, 7067.646617668993, 6999.440044796506, 7028.437724982092, 7036.472264580279, 7038.542494737826, 7045.682944267418, 6852.280510546321, 6856.0, 6857.022926702401, 6857.862842743018, 6863.965680171567, 6799.0, 7019.964900175466, 7022.754203602849, 7046.260142684944, 7079.68141433647, 6553.0, 6753.966230168818, 6798.966005169942, 6881.071055407456, 6883.177897062181], "unit": "score", "name": "prototype.html", "value": 5095.031604978006}, {"lowerIsBetter": false, "replicates": [682.2290811383136, 732.6593134192601, 733.4059411876384, 744.9106107267125, 757.503834988083, 13490.865091349084, 13634.659133521667, 13652.658683532916, 13657.590272291847, 13669.316534173302, 15362.769558456604, 15802.841971580281, 15816.367345306175, 15816.604584885383, 15824.84175158248, 835.5989125219883, 865.4850364033422, 869.1829680100717, 872.0625327772638, 873.5326600268858, 707.1973310292803, 716.0869890889106, 850.553459433796, 868.3877866104413, 869.6260607938584, 911.0388540090203, 913.8446464101119, 914.6707185413246, 915.1900567997304, 916.138829500271, 725.5719125715852, 903.1690844423114, 906.7370462565848, 907.346710368537, 908.0374802709116, 543.0008783837758, 543.2557396367002, 544.9754761035763, 659.235287067, 667.8564108716649, 14478.71042579153, 14508.782368264485, 14515.491957781427, 14529.200893950829, 14540.563783086523, 12257.57098501557, 12273.140880138393, 12331.321777302244, 12351.444185011605, 12390.31853248071, 10215.23385746072, 10226.181905447545, 10237.283390162616, 10311.0, 10567.947160264226, 3441.9311613767836, 3446.190145315874, 3463.8614455421753, 3490.9825450872836, 3682.2819550187724, 85159.0, 85607.0, 85849.57075214646, 86381.0, 86599.13400866037, 9299.581518831668, 9307.720768376825, 9455.912570054394, 9483.383580067273, 9665.951670241744, 20906.26828061041, 21065.578688426147, 21083.945802709804, 21087.261945831822, 21147.682784758243, 7372.889406658905, 7608.923910760822, 7694.692212311445, 7741.922580774233, 7957.9654644896955, 4066.0, 4091.243120022805, 4109.815058322382, 4117.300058989979, 4199.538050814408, 3543.273628906068, 3589.3180295743723, 3594.730395220369, 3594.9281014379567, 3596.6223546527763, 632.6172665537375, 641.201703878674, 645.7578408096964, 657.4674513643964, 672.939435450802, 506.45556027270095, 599.7511032921376, 599.8020653184439, 603.6619493083887, 609.6860117039728, 8198.42611017235, 8341.16588341161, 8455.985281766227, 8528.872066919, 8572.099929507312, 579.3829571506333, 580.257270693513, 592.9318128415288, 618.9907151392732, 622.483338828771, 3856.807159642007, 3864.768113913174, 3896.8051597420017, 3992.920141597152, 4065.4714887065084, 86687.0, 87879.1212087871, 88014.55992720123, 96149.59626009266, 96227.51886240524, 49602.0, 69024.0, 71348.64325678442, 71910.68535887758, 76277.85583216145, 62049.0, 62493.0, 62672.05991910125, 63291.0, 63595.682021590525, 8418.284445821928, 8508.319334453105, 8517.3580149744, 8541.444806087584, 8704.129587041245, 46794.83012924704, 48474.27288590744, 49261.76845578889, 49656.50343496519, 49977.50022499728, 64383.678081609294, 66424.0, 67407.32592674207, 67452.662736686, 67741.0, 10763.408012559072, 10788.514516846917, 10814.29707069038, 10988.175886808362, 11366.318020918772, 12037.0, 12174.87825121773, 12321.44553495077, 12417.875821241672, 12444.937775311066, 11389.829152562552, 11484.19610627248, 11741.06071514294, 11782.646520604396, 11824.82262766042, 9619.422834629944, 11334.0, 11357.37534435614, 11375.544978201111, 11745.647630571097, 11264.211505194558, 11519.42402879836, 11575.536978521102, 11599.072074233873, 11603.535858565563, 9507.382020168667, 9636.988116247692, 9671.9032809671, 9787.706368808947, 9928.751781205528, 9436.879496089783, 9633.614655413705, 9651.806963860823, 9722.416655000723, 9849.753756156153], "unit": "score", "name": "yui.html", "value": 6808.264726843898}], "name": "dromaeo_css", "value": 7539.3604252444475}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 112.0, 112.0, 110.0, 112.0, 118.0, 111.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.0}, {"lowerIsBetter": true, "replicates": [159.0, 123.0, 156.0, 192.0, 150.0, 142.0, 141.0, 117.0, 164.0, 105.0], "unit": "score", "name": "audio-beat-detection", "value": 144.9}, {"lowerIsBetter": true, "replicates": [187.0, 184.0, 185.0, 187.0, 186.0, 187.0, 184.0, 182.0, 183.0, 185.0], "unit": "score", "name": "audio-dft", "value": 185.0}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 87.0, 79.0, 58.0, 70.0, 58.0, 78.0, 78.0, 76.0], "unit": "score", "name": "audio-fft", "value": 73.9}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 131.0, 79.0, 80.0, 90.0, 110.0, 78.0, 78.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 94.0}, {"lowerIsBetter": true, "replicates": [87.0, 91.0, 86.0, 91.0, 85.0, 90.0, 86.0, 92.0, 86.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.6}, {"lowerIsBetter": true, "replicates": [95.0, 101.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 95.4}, {"lowerIsBetter": true, "replicates": [86.0, 96.0, 139.0, 133.0, 87.0, 85.0, 84.0, 87.0, 88.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 97.0}, {"lowerIsBetter": true, "replicates": [57.0, 55.0, 56.0, 56.0, 55.0, 56.0, 55.0, 69.0, 57.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 81.0, 72.0, 71.0, 67.0, 66.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [95.0, 84.0, 83.0, 84.0, 83.0, 82.0, 82.0, 83.0, 86.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.6}, {"lowerIsBetter": true, "replicates": [126.0, 124.0, 121.0, 128.0, 114.0, 130.0, 128.0, 108.0, 130.0, 120.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.9}, {"lowerIsBetter": true, "replicates": [138.0, 125.0, 136.0, 131.0, 136.0, 141.0, 137.0, 138.0, 136.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.7}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 65.0, 68.0, 96.0, 91.0, 94.0, 96.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.7}], "name": "kraken", "value": 1437.3000000000002}]} 09:30:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1437.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7539.36"}}} 09:30:29 INFO - Return code: 0 09:30:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:30:29 INFO - # TBPL SUCCESS # 09:30:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:29 INFO - 2016-02-26 09:30:29.732 screenresolution[1696:10287] starting screenresolution argv=screenresolution get 09:30:29 INFO - 2016-02-26 09:30:29.734 screenresolution[1696:10287] Display 0: 1600x1200x32@60 09:30:29 INFO - 2016-02-26 09:30:29.740 screenresolution[1697:10290] starting screenresolution argv=screenresolution list 09:30:29 INFO - Available Modes on Display 0 09:30:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:29 INFO - Intel Iris: 09:30:29 INFO - Chipset Model: Intel Iris 09:30:29 INFO - Type: GPU 09:30:29 INFO - Bus: Built-In 09:30:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:29 INFO - Vendor: Intel (0x8086) 09:30:29 INFO - Device ID: 0x0a2e 09:30:29 INFO - Revision ID: 0x0009 09:30:29 INFO - Displays: 09:30:29 INFO - Display: 09:30:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:29 INFO - Display Serial Number: 1600x1200 60 09:30:29 INFO - Main Display: Yes 09:30:29 INFO - Mirror: Off 09:30:29 INFO - Online: Yes 09:30:29 INFO - Rotation: Supported 09:30:29 INFO - Return code: 0 09:30:29 INFO - Running post-action listener: _resource_record_post_action 09:30:29 INFO - Running post-run listener: _resource_record_post_run 09:30:31 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 34135552; Write bytes: 709147648; Read time: 418; Write time: 2173 09:30:31 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401889792; Write bytes: 396605440; Read time: 30133; Write time: 731 09:30:31 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 32349696; Write bytes: 300983296; Read time: 332; Write time: 1407 09:30:31 INFO - Running post-run listener: _upload_blobber_files 09:30:31 INFO - Blob upload gear active. 09:30:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:30:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:30:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:30:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:32 INFO - (blobuploader) - INFO - Done attempting. 09:30:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:30:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:30:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:33 INFO - (blobuploader) - INFO - Done attempting. 09:30:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:33 INFO - Return code: 0 09:30:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dde003d2e3ab8d513ba3075f14f1121f35dce178afdbc4f56dad44216955dd81e866367705756966d75cbe6df352374f58ca69da6da24998ef862aa132d6f575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/049f2b6cdfe8f7d7e487fd7e4b5368a3056a00d833df25e8547d0e4a6686ee963b69a490341b4c0a2f0d8c0f3c3534d4c058aa04cb64358c4e0955adce460af3"} 09:30:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:33 INFO - Contents: 09:30:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dde003d2e3ab8d513ba3075f14f1121f35dce178afdbc4f56dad44216955dd81e866367705756966d75cbe6df352374f58ca69da6da24998ef862aa132d6f575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/049f2b6cdfe8f7d7e487fd7e4b5368a3056a00d833df25e8547d0e4a6686ee963b69a490341b4c0a2f0d8c0f3c3534d4c058aa04cb64358c4e0955adce460af3"} 09:30:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:33 INFO - Copying logs to upload dir... 09:30:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.245994 ========= master_lag: 13.85 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-02-26 09:30:47.291481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 21 secs) (at 2016-02-26 09:30:47.297282) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/dde003d2e3ab8d513ba3075f14f1121f35dce178afdbc4f56dad44216955dd81e866367705756966d75cbe6df352374f58ca69da6da24998ef862aa132d6f575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/049f2b6cdfe8f7d7e487fd7e4b5368a3056a00d833df25e8547d0e4a6686ee963b69a490341b4c0a2f0d8c0f3c3534d4c058aa04cb64358c4e0955adce460af3"} build_url:https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011273 build_url: 'https://queue.taskcluster.net/v1/task/Io22gq35RtWKveljy6qxaw/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/dde003d2e3ab8d513ba3075f14f1121f35dce178afdbc4f56dad44216955dd81e866367705756966d75cbe6df352374f58ca69da6da24998ef862aa132d6f575", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/049f2b6cdfe8f7d7e487fd7e4b5368a3056a00d833df25e8547d0e4a6686ee963b69a490341b4c0a2f0d8c0f3c3534d4c058aa04cb64358c4e0955adce460af3"}' ========= master_lag: 21.92 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 21 secs) (at 2016-02-26 09:31:09.224774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-02-26 09:31:09.225080) ========= 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.8CNA4Ix3fB/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.JBkWVk4ITb/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.004693 ========= master_lag: 5.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-02-26 09:31:14.273500) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-02-26 09:31:14.273770) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-02-26 09:31:20.739766) ========= ========= Total master_lag: 44.68 =========