builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0144 starttime: 1455210099.02 results: success (0) buildid: 20160211071132 builduid: 5bbad53408c9416ea875518efed1b061 revision: fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.016843) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.017202) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.017495) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005196 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.039978) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.040252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.056138) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.056429) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005152 ========= master_lag: 0.55 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.607744) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.608077) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.608473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:39.608773) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-11 09:01:39-- 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% 17.4M=0.001s 2016-02-11 09:01:40 (17.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178954 ========= master_lag: 0.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:40.086042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:40.086342) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.026056 ========= master_lag: 0.59 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:40.700319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:40.702704) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e --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 fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-11 09:01:40,799 truncating revision to first 12 chars 2016-02-11 09:01:40,799 Setting DEBUG logging. 2016-02-11 09:01:40,799 attempt 1/10 2016-02-11 09:01:40,799 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fe8b8550ff4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-11 09:01:41,458 unpacking tar archive at: fx-team-fe8b8550ff4b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.903876 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:41.646021) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:41.646318) ========= echo fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e program finished with exit code 0 elapsedTime=0.005374 script_repo_revision: 'fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:41.694751) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:41.695028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 09:01:41.707138) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-11 09:01:41.707402) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:01:41 INFO - MultiFileLogger online at 20160211 09:01:41 in /builds/slave/test 09:01:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:01:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:41 INFO - {'append_to_log': False, 09:01:41 INFO - 'base_work_dir': '/builds/slave/test', 09:01:41 INFO - 'blob_upload_branch': 'Fx-Team', 09:01:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:41 INFO - 'branch': 'Fx-Team', 09:01:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:41 INFO - 'config_files': ('talos/mac_config.py',), 09:01:41 INFO - 'default_actions': ('clobber', 09:01:41 INFO - 'read-buildbot-config', 09:01:41 INFO - 'download-and-extract', 09:01:41 INFO - 'populate-webroot', 09:01:41 INFO - 'create-virtualenv', 09:01:41 INFO - 'install', 09:01:41 INFO - 'run-tests'), 09:01:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:41 INFO - 'download_minidump_stackwalk': True, 09:01:41 INFO - 'download_symbols': 'ondemand', 09:01:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:41 INFO - 'installer_path': 'installer.exe', 09:01:41 INFO - 'log_level': 'info', 09:01:41 INFO - 'log_name': 'talos', 09:01:41 INFO - 'log_to_console': True, 09:01:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:41 INFO - 'opt_config_files': (), 09:01:41 INFO - 'pip_index': False, 09:01:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:41 INFO - 'cmd': ('bash', 09:01:41 INFO - '-c', 09:01:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:41 INFO - 'enabled': True, 09:01:41 INFO - 'halt_on_failure': False, 09:01:41 INFO - 'name': 'check_screen_resolution'},), 09:01:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:41 INFO - 'cmd': ('bash', 09:01:41 INFO - '-c', 09:01:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:41 INFO - 'enabled': True, 09:01:41 INFO - 'halt_on_failure': False, 09:01:41 INFO - 'name': 'check_screen_resolution'},), 09:01:41 INFO - 'run_cmd_checks_enabled': True, 09:01:41 INFO - 'sps_profile': False, 09:01:41 INFO - 'sps_profile_interval': 0, 09:01:41 INFO - 'suite': 'dromaeojs-e10s', 09:01:41 INFO - 'system_bits': '32', 09:01:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:41 INFO - 'title': 't-yosemite-r7-0144', 09:01:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:41 INFO - 'use_talos_json': True, 09:01:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:41 INFO - 'work_dir': 'build'} 09:01:41 INFO - ##### 09:01:41 INFO - ##### Running clobber step. 09:01:41 INFO - ##### 09:01:41 INFO - Running pre-action listener: _resource_record_pre_action 09:01:41 INFO - Running main action method: clobber 09:01:41 INFO - rmtree: /builds/slave/test/build 09:01:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:43 INFO - Running post-action listener: _resource_record_post_action 09:01:43 INFO - ##### 09:01:43 INFO - ##### Running read-buildbot-config step. 09:01:43 INFO - ##### 09:01:43 INFO - Running pre-action listener: _resource_record_pre_action 09:01:43 INFO - Running main action method: read_buildbot_config 09:01:43 INFO - Using buildbot properties: 09:01:43 INFO - { 09:01:43 INFO - "project": "", 09:01:43 INFO - "product": "firefox", 09:01:43 INFO - "script_repo_revision": "production", 09:01:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:01:43 INFO - "repository": "", 09:01:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:01:43 INFO - "buildid": "20160211071132", 09:01:43 INFO - "pgo_build": "False", 09:01:43 INFO - "basedir": "/builds/slave/test", 09:01:43 INFO - "buildnumber": 364, 09:01:43 INFO - "slavename": "t-yosemite-r7-0144", 09:01:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:01:43 INFO - "platform": "yosemite_r7", 09:01:43 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:01:43 INFO - "branch": "fx-team", 09:01:43 INFO - "repo_path": "integration/fx-team", 09:01:43 INFO - "revision": "fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e", 09:01:43 INFO - "stage_platform": "macosx64", 09:01:43 INFO - "builduid": "5bbad53408c9416ea875518efed1b061", 09:01:43 INFO - "slavebuilddir": "test" 09:01:43 INFO - } 09:01:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 09:01:43 INFO - Running post-action listener: _resource_record_post_action 09:01:43 INFO - ##### 09:01:43 INFO - ##### Running download-and-extract step. 09:01:43 INFO - ##### 09:01:43 INFO - Running pre-action listener: _resource_record_pre_action 09:01:43 INFO - Running main action method: download_and_extract 09:01:43 INFO - mkdir: /builds/slave/test/build/tests 09:01:43 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:01:43 INFO - https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/test_packages.json 09:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/test_packages.json 09:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:01:44 INFO - Downloaded 1277 bytes. 09:01:44 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:01:44 INFO - Using the following test package requirements: 09:01:44 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:01:44 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:44 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 09:01:44 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'jsshell-mac.zip'], 09:01:44 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 09:01:44 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:01:44 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 09:01:44 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 09:01:44 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 09:01:44 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 09:01:44 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 09:01:44 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:44 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:44 INFO - https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:01:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:01:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:45 INFO - Downloaded 17755061 bytes. 09:01:45 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:01:45 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:01:49 INFO - Return code: 0 09:01:49 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:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:49 INFO - https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:01:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 09:01:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:53 INFO - Downloaded 17755061 bytes. 09:01:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:01:56 INFO - Return code: 0 09:01: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:01:56 INFO - https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:01:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:01:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 09:01:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:01:57 INFO - Downloaded 10706547 bytes. 09:01:57 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:01:57 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:01:57 INFO - Return code: 0 09:01: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:01:57 INFO - https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:01:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:01:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:01:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 09:01:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cqBrvq7gQHmpdd8kwM0RpA/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:02:01 INFO - Downloaded 111131577 bytes. 09:02:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:02:01 INFO - mkdir: /builds/slave/test/properties 09:02:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:01 INFO - Contents: 09:02:01 INFO - build_url:https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 09:02:02 INFO - Running post-action listener: _resource_record_post_action 09:02:02 INFO - Running post-action listener: set_extra_try_arguments 09:02:02 INFO - ##### 09:02:02 INFO - ##### Running populate-webroot step. 09:02:02 INFO - ##### 09:02:02 INFO - Running pre-action listener: _resource_record_pre_action 09:02:02 INFO - Running main action method: populate_webroot 09:02:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:02 INFO - '--tppagecycles', 09:02:02 INFO - '7'], 09:02:02 INFO - 'tests': ['tsvgm']}}, 09:02:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:02:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:02:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'tests': ['damp', 'tps']}, 09:02:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['damp', 'tps']}, 09:02:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:02:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:02 INFO - 'other': {'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'other_l64': {'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'other_nol64': {'tests': ['a11yr', 09:02:02 INFO - 'ts_paint', 09:02:02 INFO - 'tpaint', 09:02:02 INFO - 'sessionrestore', 09:02:02 INFO - 'sessionrestore_no_auto_restore']}, 09:02:02 INFO - 'svgr': {'tests': ['tsvgx', 09:02:02 INFO - 'tsvgr_opacity', 09:02:02 INFO - 'tart', 09:02:02 INFO - 'tscrollx', 09:02:02 INFO - 'cart']}, 09:02:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['tsvgx', 09:02:02 INFO - 'tsvgr_opacity', 09:02:02 INFO - 'tart', 09:02:02 INFO - 'tscrollx', 09:02:02 INFO - 'cart']}, 09:02:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'tests': ['tp5o']}, 09:02:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'talos_options': ['--e10s'], 09:02:02 INFO - 'tests': ['tp5o']}, 09:02:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'talos_options': ['--xperf_path', 09:02:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:02 INFO - 'tests': ['tp5n']}, 09:02:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:02 INFO - 'talos_options': ['--e10s', 09:02:02 INFO - '--xperf_path', 09:02:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:02 INFO - 'tests': ['tp5n']}}, 09:02:02 INFO - 'talos.zip': {'path': '', 09:02:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:02 INFO - Running post-action listener: _resource_record_post_action 09:02:02 INFO - ##### 09:02:02 INFO - ##### Running create-virtualenv step. 09:02:02 INFO - ##### 09:02:02 INFO - Running pre-action listener: _resource_record_pre_action 09:02:02 INFO - Running main action method: create_virtualenv 09:02:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:02 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:02:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:02 INFO - Using real prefix '/tools/python27' 09:02:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:05 INFO - Installing pip.................done. 09:02:05 INFO - Return code: 0 09:02:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:05 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:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:05 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:02:05 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:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:05 INFO - 'HOME': '/Users/cltbld', 09:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:05 INFO - 'LOGNAME': 'cltbld', 09:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:05 INFO - 'MOZ_NO_REMOTE': '1', 09:02:05 INFO - 'NO_EM_RESTART': '1', 09:02:05 INFO - 'PAGER': '/bin/cat', 09:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:05 INFO - 'PWD': '/builds/slave/test', 09:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:05 INFO - 'SHELL': '/bin/bash', 09:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:05 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:05 INFO - 'USER': 'cltbld', 09:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:05 INFO - 'XPC_FLAGS': '0x0', 09:02:05 INFO - 'XPC_SERVICE_NAME': '0', 09:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:06 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:02:06 INFO - Cleaning up... 09:02:06 INFO - Return code: 0 09:02:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:02:06 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:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:06 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:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:06 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:06 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:02:06 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:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:06 INFO - 'HOME': '/Users/cltbld', 09:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:06 INFO - 'LOGNAME': 'cltbld', 09:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:06 INFO - 'MOZ_NO_REMOTE': '1', 09:02:06 INFO - 'NO_EM_RESTART': '1', 09:02:06 INFO - 'PAGER': '/bin/cat', 09:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:06 INFO - 'PWD': '/builds/slave/test', 09:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:06 INFO - 'SHELL': '/bin/bash', 09:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:06 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:06 INFO - 'USER': 'cltbld', 09:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:06 INFO - 'XPC_FLAGS': '0x0', 09:02:06 INFO - 'XPC_SERVICE_NAME': '0', 09:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:06 INFO - Downloading/unpacking psutil>=0.7.1 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:09 INFO - Installing collected packages: psutil 09:02:09 INFO - Running setup.py install for psutil 09:02:09 INFO - building 'psutil._psutil_osx' extension 09:02:09 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:02:09 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:02:09 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:02:10 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:02:10 INFO - building 'psutil._psutil_posix' extension 09:02:10 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:02:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:10 INFO - ^ 09:02:10 INFO - 1 warning generated. 09:02:10 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:02:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:10 INFO - Successfully installed psutil 09:02:10 INFO - Cleaning up... 09:02:10 INFO - Return code: 0 09:02:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:02: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:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:10 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:10 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:02:10 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:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:10 INFO - 'HOME': '/Users/cltbld', 09:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:10 INFO - 'LOGNAME': 'cltbld', 09:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:10 INFO - 'MOZ_NO_REMOTE': '1', 09:02:10 INFO - 'NO_EM_RESTART': '1', 09:02:10 INFO - 'PAGER': '/bin/cat', 09:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:10 INFO - 'PWD': '/builds/slave/test', 09:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:10 INFO - 'SHELL': '/bin/bash', 09:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:10 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:10 INFO - 'USER': 'cltbld', 09:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:10 INFO - 'XPC_FLAGS': '0x0', 09:02:10 INFO - 'XPC_SERVICE_NAME': '0', 09:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:13 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:02:13 INFO - Installing collected packages: mozsystemmonitor 09:02:13 INFO - Running setup.py install for mozsystemmonitor 09:02:13 INFO - Successfully installed mozsystemmonitor 09:02:13 INFO - Cleaning up... 09:02:13 INFO - Return code: 0 09:02:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:13 INFO - 'HOME': '/Users/cltbld', 09:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:13 INFO - 'LOGNAME': 'cltbld', 09:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:13 INFO - 'MOZ_NO_REMOTE': '1', 09:02:13 INFO - 'NO_EM_RESTART': '1', 09:02:13 INFO - 'PAGER': '/bin/cat', 09:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:13 INFO - 'PWD': '/builds/slave/test', 09:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:13 INFO - 'SHELL': '/bin/bash', 09:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:13 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:13 INFO - 'USER': 'cltbld', 09:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:13 INFO - 'XPC_FLAGS': '0x0', 09:02:13 INFO - 'XPC_SERVICE_NAME': '0', 09:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 INFO - Downloading docopt-0.6.1.tar.gz 09:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:17 INFO - Running setup.py install for blobuploader 09:02:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:17 INFO - Running setup.py install for requests 09:02:17 INFO - Running setup.py install for docopt 09:02:17 INFO - Successfully installed blobuploader requests docopt 09:02:17 INFO - Cleaning up... 09:02:17 INFO - Return code: 0 09:02:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:17 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:17 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:02:17 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:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:17 INFO - 'HOME': '/Users/cltbld', 09:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:17 INFO - 'LOGNAME': 'cltbld', 09:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:17 INFO - 'MOZ_NO_REMOTE': '1', 09:02:17 INFO - 'NO_EM_RESTART': '1', 09:02:17 INFO - 'PAGER': '/bin/cat', 09:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:17 INFO - 'PWD': '/builds/slave/test', 09:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:17 INFO - 'SHELL': '/bin/bash', 09:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:17 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:17 INFO - 'USER': 'cltbld', 09:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:17 INFO - 'XPC_FLAGS': '0x0', 09:02:17 INFO - 'XPC_SERVICE_NAME': '0', 09:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Z_srMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-riLzwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-fdj_DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-N4mst0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-sYOBAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-WTRBZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-t0H4dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-OeX2pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-mHdSdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jXYyNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-sfd0iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-LZqI0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8PStMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-P2kH8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-KpEhj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-PvaLxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:19 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-ntWE1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:19 INFO - Running setup.py install for manifestparser 09:02:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:20 INFO - Running setup.py install for mozcrash 09:02:20 INFO - Running setup.py install for mozdebug 09:02:20 INFO - Running setup.py install for mozdevice 09:02:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:20 INFO - Running setup.py install for mozfile 09:02:20 INFO - Running setup.py install for mozhttpd 09:02:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:20 INFO - Running setup.py install for mozinfo 09:02:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Running setup.py install for mozInstall 09:02:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Running setup.py install for mozleak 09:02:21 INFO - Running setup.py install for mozlog 09:02:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Running setup.py install for moznetwork 09:02:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Running setup.py install for mozprocess 09:02:21 INFO - Running setup.py install for mozprofile 09:02:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:21 INFO - Running setup.py install for mozrunner 09:02:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:22 INFO - Running setup.py install for mozscreenshot 09:02:22 INFO - Running setup.py install for moztest 09:02:22 INFO - Running setup.py install for mozversion 09:02:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:22 INFO - Cleaning up... 09:02:22 INFO - Return code: 0 09:02:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:22 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:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 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:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:22 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:02:22 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:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:22 INFO - 'HOME': '/Users/cltbld', 09:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:22 INFO - 'LOGNAME': 'cltbld', 09:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:22 INFO - 'MOZ_NO_REMOTE': '1', 09:02:22 INFO - 'NO_EM_RESTART': '1', 09:02:22 INFO - 'PAGER': '/bin/cat', 09:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:22 INFO - 'PWD': '/builds/slave/test', 09:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:22 INFO - 'SHELL': '/bin/bash', 09:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:22 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:22 INFO - 'USER': 'cltbld', 09:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:22 INFO - 'XPC_FLAGS': '0x0', 09:02:22 INFO - 'XPC_SERVICE_NAME': '0', 09:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8Fd7Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-eymf6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-5LKvbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-tc9m0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-23WsHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-PIkKWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Hsl5zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:23 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:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:23 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-KuZsRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-YogpKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-1bAoVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-zg6LmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-VpNfuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-_qK206-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-PXSQkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-vm07Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-YzB2l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:24 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:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:24 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-cAG7zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:27 INFO - Downloading blessings-1.6.tar.gz 09:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:27 INFO - Installing collected packages: blessings 09:02:27 INFO - Running setup.py install for blessings 09:02:27 INFO - Successfully installed blessings 09:02:27 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:28 INFO - Reading from file tmpfile_stdout 09:02:28 INFO - Current package versions: 09:02:28 INFO - blessings == 1.6 09:02:28 INFO - blobuploader == 1.2.4 09:02:28 INFO - docopt == 0.6.1 09:02:28 INFO - manifestparser == 1.1 09:02:28 INFO - mozInstall == 1.12 09:02:28 INFO - mozcrash == 0.16 09:02:28 INFO - mozdebug == 0.1 09:02:28 INFO - mozdevice == 0.48 09:02:28 INFO - mozfile == 1.2 09:02:28 INFO - mozhttpd == 0.7 09:02:28 INFO - mozinfo == 0.9 09:02:28 INFO - mozleak == 0.1 09:02:28 INFO - mozlog == 3.1 09:02:28 INFO - moznetwork == 0.27 09:02:28 INFO - mozprocess == 0.22 09:02:28 INFO - mozprofile == 0.28 09:02:28 INFO - mozrunner == 6.11 09:02:28 INFO - mozscreenshot == 0.1 09:02:28 INFO - mozsystemmonitor == 0.0 09:02:28 INFO - moztest == 0.7 09:02:28 INFO - mozversion == 1.4 09:02:28 INFO - psutil == 3.1.1 09:02:28 INFO - requests == 1.2.3 09:02:28 INFO - wsgiref == 0.1.2 09:02:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:28 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:02:28 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:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:28 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:02:28 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:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Downloading/unpacking jsonschema 09:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:31 INFO - Downloading/unpacking functools32 (from jsonschema) 09:02:31 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:02:31 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:02:31 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:02:31 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:02:31 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:02:31 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:02:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:31 INFO - warning: no files found matching '*.txt' 09:02:31 INFO - no previously-included directories found matching 'build' 09:02:31 INFO - no previously-included directories found matching 'dist' 09:02:31 INFO - no previously-included directories found matching '.git*' 09:02:31 INFO - Installing collected packages: jsonschema, functools32 09:02:31 INFO - Running setup.py install for functools32 09:02:31 INFO - warning: no files found matching '*.txt' 09:02:31 INFO - no previously-included directories found matching 'build' 09:02:31 INFO - no previously-included directories found matching 'dist' 09:02:31 INFO - no previously-included directories found matching '.git*' 09:02:31 INFO - Successfully installed jsonschema functools32 09:02:31 INFO - Cleaning up... 09:02:31 INFO - Return code: 0 09:02:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:02: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:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:31 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 0x101ac4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd13435c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, '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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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:02:31 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:02:31 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:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:31 INFO - 'HOME': '/Users/cltbld', 09:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:31 INFO - 'LOGNAME': 'cltbld', 09:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:31 INFO - 'MOZ_NO_REMOTE': '1', 09:02:31 INFO - 'NO_EM_RESTART': '1', 09:02:31 INFO - 'PAGER': '/bin/cat', 09:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:31 INFO - 'PWD': '/builds/slave/test', 09:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:31 INFO - 'SHELL': '/bin/bash', 09:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:02:31 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:31 INFO - 'USER': 'cltbld', 09:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:31 INFO - 'XPC_FLAGS': '0x0', 09:02:31 INFO - 'XPC_SERVICE_NAME': '0', 09:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:32 INFO - Downloading/unpacking flake8 09:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:02:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:02:34 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:02:34 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:02:34 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:02:34 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:02:34 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:02:34 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:02:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:02:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:02:34 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:02:34 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:02:34 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:02:34 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:02:34 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:02:34 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:02:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:02:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:35 INFO - no previously-included directories found matching 'docs/_build' 09:02:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:02:35 INFO - Running setup.py install for pep8 09:02:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:35 INFO - no previously-included directories found matching 'docs/_build' 09:02:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:02:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:02:35 INFO - Cleaning up... 09:02:36 INFO - Return code: 0 09:02:36 INFO - Running post-action listener: _resource_record_post_action 09:02:36 INFO - Running post-action listener: _start_resource_monitoring 09:02:36 INFO - Starting resource monitoring. 09:02:36 INFO - ##### 09:02:36 INFO - ##### Running install step. 09:02:36 INFO - ##### 09:02:36 INFO - Running pre-action listener: _resource_record_pre_action 09:02:36 INFO - Running main action method: install 09:02:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:36 INFO - Reading from file tmpfile_stdout 09:02:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:36 INFO - Reading from file tmpfile_stdout 09:02:36 INFO - Output received: 09:02:36 INFO - Usage: mozinstall [options] installer 09:02:36 INFO - Options: 09:02:36 INFO - -h, --help show this help message and exit 09:02:36 INFO - -d DEST, --destination=DEST 09:02:36 INFO - Directory to install application into. [default: 09:02:36 INFO - "/builds/slave/test"] 09:02:36 INFO - --app=APP Application being installed. [default: firefox] 09:02:36 INFO - mkdir: /builds/slave/test/build/application 09:02:36 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:02:36 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:03:10 INFO - Reading from file tmpfile_stdout 09:03:10 INFO - Output received: 09:03:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:10 INFO - Running post-action listener: _resource_record_post_action 09:03:10 INFO - ##### 09:03:10 INFO - ##### Running run-tests step. 09:03:10 INFO - ##### 09:03:10 INFO - Running pre-action listener: _resource_record_pre_action 09:03:10 INFO - Running main action method: run_tests 09:03:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:10 INFO - 2016-02-11 09:03:10.139 screenresolution[1649:7576] starting screenresolution argv=screenresolution get 09:03:10 INFO - 2016-02-11 09:03:10.142 screenresolution[1649:7576] Display 0: 1600x1200x32@60 09:03:10 INFO - 2016-02-11 09:03:10.148 screenresolution[1650:7578] starting screenresolution argv=screenresolution list 09:03:10 INFO - Available Modes on Display 0 09:03:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:10 INFO - Intel Iris: 09:03:10 INFO - Chipset Model: Intel Iris 09:03:10 INFO - Type: GPU 09:03:10 INFO - Bus: Built-In 09:03:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:10 INFO - Vendor: Intel (0x8086) 09:03:10 INFO - Device ID: 0x0a2e 09:03:10 INFO - Revision ID: 0x0009 09:03:10 INFO - Displays: 09:03:10 INFO - Display: 09:03:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:10 INFO - Display Serial Number: 1600x1200 60 09:03:10 INFO - Main Display: Yes 09:03:10 INFO - Mirror: Off 09:03:10 INFO - Online: Yes 09:03:10 INFO - Rotation: Supported 09:03:10 INFO - Return code: 0 09:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:10 INFO - Python 2.7.3 09:03:10 INFO - Return code: 0 09:03:10 INFO - grabbing minidump binary from tooltool 09:03: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:03:10 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 0x101968d30>, '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 0x10178cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10195fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:10 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:03:10 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:03:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:10 INFO - Return code: 0 09:03:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:03:11 INFO - Return code: 0 09:03:11 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:03:11 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0144 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p4o4cFHhlT/Render', 09:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:11 INFO - 'HOME': '/Users/cltbld', 09:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:11 INFO - 'LOGNAME': 'cltbld', 09:03:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:11 INFO - 'MOZ_NO_REMOTE': '1', 09:03:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:11 INFO - 'NO_EM_RESTART': '1', 09:03:11 INFO - 'PAGER': '/bin/cat', 09:03:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:11 INFO - 'PWD': '/builds/slave/test', 09:03:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:11 INFO - 'SHELL': '/bin/bash', 09:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bUcdsSRsIC/Listeners', 09:03:11 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:11 INFO - 'USER': 'cltbld', 09:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:11 INFO - 'XPC_FLAGS': '0x0', 09:03:11 INFO - 'XPC_SERVICE_NAME': '0', 09:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:03:11 INFO - mozversion application_buildid: 20160211071132 09:03:11 INFO - mozversion application_changeset: fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e 09:03:11 INFO - mozversion application_display_name: Nightly 09:03:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:11 INFO - mozversion application_name: Firefox 09:03:11 INFO - mozversion application_remotingname: firefox 09:03:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:03:11 INFO - mozversion application_vendor: Mozilla 09:03:11 INFO - mozversion application_version: 47.0a1 09:03:11 INFO - mozversion platform_buildid: 20160211071132 09:03:11 INFO - mozversion platform_changeset: fe8b8550ff4b0a6aada0a67dd65979f8668c7e0e 09:03:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:03:11 INFO - mozversion platform_version: 47.0a1 09:03:11 INFO - using testdate: 1455210191 09:03:11 INFO - actual date: 1455210191 09:03:12 INFO - starting webserver on 'localhost:49274' 09:03:12 INFO - SUITE-START | Running 2 tests 09:03:12 INFO - TEST-START | dromaeo_css 09:03:12 INFO - operating with platform_type : mac_ 09:03:12 INFO - Initialising browser for dromaeo_css test... 09:03:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpuxG3mf/profile http://localhost:49274/getInfo.html) 09:03:15 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 09:03:15 INFO - PROCESS | 1668 | colorDepth:24 09:03:15 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 09:03:15 INFO - PROCESS | 1668 | __metrics 09:03:15 INFO - TEST-INFO | 1668: exit 0 09:03:15 INFO - Browser initialized. 09:03:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:15 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpuxG3mf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:27 INFO - PROCESS | 1672 | RSS: Main: 258895872 09:03:27 INFO - PROCESS | 1672 | 09:04:17 INFO - PROCESS | 1672 | 1455210257608 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:06:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 09:06:28 INFO - PROCESS | 1672 | RSS: Main: 272728064 09:06:28 INFO - PROCESS | 1672 | 09:09:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 09:09:30 INFO - PROCESS | 1672 | RSS: Main: 282394624 09:09:30 INFO - PROCESS | 1672 | 09:12:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 09:12:33 INFO - PROCESS | 1672 | RSS: Main: 284205056 09:12:33 INFO - PROCESS | 1672 | 09:15:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 09:15:35 INFO - PROCESS | 1672 | RSS: Main: 286797824 09:15:35 INFO - PROCESS | 1672 | 09:18:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 09:18:38 INFO - PROCESS | 1672 | RSS: Main: 287932416 09:18:38 INFO - PROCESS | 1672 | 09:21:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 09:21:41 INFO - PROCESS | 1672 | RSS: Main: 290553856 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | __start_tp_report 09:21:41 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 09:21:41 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 09:21:41 INFO - PROCESS | 1672 | |i|pagename|runs| 09:21:41 INFO - PROCESS | 1672 | |0;dojo.html;2887.2348827560695;3329.6015673417164;3370.511275865;3579.2125732338877;3607.2965771674517;4458.4426946631675;5235.371755389349;5766.942330576699;5799.246098007258;5809.709514524278;5642.858928526779;5743.368229494752;5771.855703607412;5774;5955;5139.845804625857;5209.843704688866;5228.5555727763185;5249.186376111708;5285.312909321783;71.99603399009992;5123.2315152727015;5185.118529849935;5199.610029247802;5228.686278823283;5182.896342073156;5200.68795872247;5201.713905735183;5214.400343960455;5222.738863056851;5181.559567436772;5199.974000130013;5205.193195054772;5222.373315202162;5259.053370393328;5089.134847076006;5116.232565115225;5187.455317191716;5196.870078248036;5246.2655228267695;5592.7762889484375;5621.747021384048;5679.886402271932;5759.078547432391;5768.913466298009;6092.482139018144;6188.9690551546955;6313.96843015782;6315.305316415191;6347.301796802348;6294.716737746812;6310.148130002437;6322.367763223687;6324.849551152584;6368.713407896656;5867.413258674141;5876.61801982874;5879.088741245114;5903.439173278532;5939.643621382731;5324.334458192726;6197.721102550306;6234.9688251558455;6303.779367722108;6319.9368006320265;6221.813345599639;6237.5945563539135;6244.812655620339;6245;6249.78125765596;5906.645601263938;6151.954167791486;6233.096201050913;6269.561130720897;6290.748370065237;6131.468419844996;6162.22972128484;6190.916589596803;6285.340039295899;6291.622502649856;6228.906566401508;6285.2457705075685;6297.527685423612;6320.683965801692;6324.659875845967;4811.350467686888;4891.804327826847;4929.482404347564;4939.851804445872;4982.950170498321;4391.121775644884;4406.228909940747;4412.757298348622;4425.845095421645;4429.202743506135;5548.889022219614;5581.6930068845795;5585.692786896679;5617.662940223599;5625.803096891589;6159.538034647419;6160.661163636043;6175.938240617626;6216.844578885473;6338.080978258126;4797.376341075638;5747.655140691572;5757.510611598061;5781.335146458127;5801.3328467225965;5279.630425870229;5388.164834450667;5401.243825864382;5416.647917885403;5429.158480435539;6302.779402720883;6325.051242313598;6328.860805055135;6335.714892829741;6352.142460767737;6263.311035786151;6283.560150789401;6323.588966717149;6336.08126821599;6347.238331400262;6188.257409110755;6276.968615157078;6289.930711778917;6318.62088274705;6321.462675672436;5515.393306736336;6210.759540939494;6251.249850018027;6284.497240220863;6288.0882272069375;6279.96860015697;6284.591501552384;6321.873562528632;6323.715432805443;6338.207724034496;6062.909056364247;6083.300420451705;6132.969335153296;6157.168782214344;6168.074788781719;6143.341317722334;6149.692515374124;6159.952808022557;6169.784057558143;6215.06773983906;5932.525397968238;5989.730462129128;6098.054801505774;6117.174181485442;6118.326984031842;6076.1493390924425;6142.232220972379;6173.492821055704;6174.536909731698;6192.225971753531;6053.804979294312;6082.635041897501;6094.8171554853425;6100.115483254992;6109.816705498843 09:21:41 INFO - PROCESS | 1672 | |1;ext.html;449.05922093214724;457.97481138537376;470.7128651522573;556.6945512772548;610.5481943361908;35498.112547186334;37827.810860945654;37843.053923651925;37892;37911.86264412069;39123.60876391235;39582;39643.207135857265;39698.206035879266;39733.40399894004;9382.0148884367;9580.281478889076;9621.470819104947;9627.085426884434;9635.470049147294;7153.284671532857;9169.312301577374;9367.765805854873;9595.472249026301;9617.56720947559;9597.992210817869;9638.614455421775;9650.179734722557;9652.938176800546;9705.175060119898;9579.712608621752;9603;9609.13517783396;9628.662996795114;9650.08324209199;9530.666426675103;9623.56693948774;9626.422414655073;9633.988431214762;9675.758106047335;37816.81091594524;38315.04212394685;38326.80836595827;38374.040648983995;38441.42337864934;3204.839758012113;3359.445691460906;3563.376409128418;3703.9444408333898;3734.5705243897028;28206.71793282082;28377.290567735767;28395.858020709766;29333.26666833325;29559.96540121107;2811.8734656940283;2836.5886946392657;2848.458792829356;2861.370498490329;2890.7976441649093;485991;491340.08659913304;491972;496569;496656.9999999964;44826.77586612046;45389.54610453853;67359.66320168368;67899.98150027753;67902.66048669824;44140.55859441365;44208.3368749469;44475;44580.66258012265;44736.77631611821;15104.773428398583;15207;15231.847681523264;15302.923485382502;15322.693546129016;11918.284902905853;12166.209196402206;12183.147179697513;12237.449314780857;12323.815142772866;14347;14367.568972930829;14613.26933653313;14708.485203017843;14830.480933167288;6250;6365.586236894587;6381;6400.743970241231;6407.935920640734;930.0559931669361;954.9856752148723;1026.2149455666422;1058.5342449322427;1085.8045551800594;29903.850480747456;30066.248343791143;30174;30264.54603180954;30464.84767576148;1089.8855620159927;1094.5567045346488;1126.4311522680994;1161.9593314233962;1192.43359404284;13392;14352.851771858226;15153.924230378998;15188.088714677155;15447.613809654624;537435;538641.613583867;542733;544103;544542;45746.542534574895;56172.71913640487;57033;57212.14181787276;59321.70339148363;1389.7637401641746;1404.6347949533194;1406.2617126008722;1417.5534706567605;1435.7415665180158;28606.284842879093;29467.55798662979;29683.70316296896;30033.849830750707;30149.397012060075;51342;51668;51894.74052629713;51963.480365195865;51973.22040169325;499708;502385.4880725719;505094;505723;506152;33074.5038824413;43243.78378108089;44142.77928610337;44683.88290292639;44738.77630611826;42286.73139805811;42627.360589590564;42731.57268427276;43301.35047974346;43868.56131438645;35469.113272168404;37163.25673486569;37592.87221383363;37752.62247377491;37905.62094379021;36341.45487817738;36519.81740091283;36776;36843.81578092092;37122.88631341064;36019;37027.372932644386;37028.259434810614;37345;37488.2502349946;30792.384152317278;33226.6709331624;33402.665973340925;33463.33073338471;33524.329513410084;24687.135950241656;25389.74610253874;25839.354016149748;25940.740592593833;26340.341491462867 09:21:41 INFO - PROCESS | 1672 | |2;jquery.html;343.8109040027984;363.25895173845356;365.37520839364686;367.77381910125274;371.10193332136225;15568.45510407136;18771.343002994898;19291.614167716656;19375.903120484414;20034.098465569066;11503.712407189803;14446.277686115705;18207.817921820777;18868.81131188688;18965.715514267296;3918.0009097680195;5045.8486245412605;5063.949360506384;5321.122014867542;5322.2282768998475;5840.211571437865;5909.7340619672;5944.524438044968;5945.316288626798;5955;5894.793682221101;6305;6358.268799088095;6374.298827129035;6381.7766378176775;5797.739101740432;5908.497777688902;5912.142739302777;6310.747570097191;6325.051242313643;5586.832395028156;5640.887182256373;5648.63283886546;5648.661080335193;5655.943440565583;13248.73502529944;13534.335692508193;13604.795928061087;13618.795718064237;24803.751962480324;1993.132987150594;2105.6841473778904;2153.838462115332;2403.644846768875;2540.695116586003;9516.23870090391;9710.514474276259;10048.547815348327;12069.758604827854;14037.947153963494;1781.910904454772;1829.2225804033367;1841.254292011724;1864.2449807827852;1871.4853415310763;75704;94954.0504594952;100455.49772251165;101851.4722279159;104199;10795.514201860935;11064.17018723599;11216.663500094846;11252.43737813106;11326.980571748581;11658.766824663458;13174.538891138764;13186.670333241555;13203.603891883258;13227;6768.864622707519;7909.52542847431;8300.792480187924;8350.749477515563;8375.916240837636;8330.666773329;8357.373197010249;8462;8499.065102838682;8558;4034.5561988181275;4277.743335399824;4444.622207112367;4444.644428445717;4459.152760975404;1880.125741530195;1896.3268039845932;1898.4589392023483;1902.172554938606;1904.990355111793;760.5284723471483;827.2637352756052;902.7697937026147;985.9161971232526;987.1362557761959;19800.00999949997;20659;20738.066786994623;20869;20869.68695469569;908.5321059654283;971.1162841813915;977.7117384843982;1011.3476807459148;1030.7320096774893;9290.860637090314;10608.41653709038;10918.563257469772;10960.342379457257;11009.119270458345;754049;758174;761697;774228;774868;111093.33359999607;115858.4207078976;116690;117511.8248817518;118165.40917295359;1199.1665792274287;1202.7895118354254;1254.4417734108417;1260.6785269756153;1306.764782339188;18411.079446027376;18512.0743962804;18721;18831.623367532848;18904.905475472533;44947;48915.75542122267;50301.993960119864;50548.74725626349;50661;69141.65429173023;69807.30192698009;71575.64212178906;72584.54830903458;72949.63525182553;5507.779688812403;5767.884642307215;6304.684765761602;7486.326230639269;7708;9160.6793762218;11351.091912646803;11772.411379430823;12079;12195.898865220272;10128.544215510172;10311.896881031094;10537.157027437634;10542.103921166787;10604.045635892806;9623.807523849624;9707.514624268899;9723.076307750753;10245.334053286513;10405.635802746867;8450.830983380421;10312.845307320245;10658.62694805678;10747.193960452842;10766;7531.3598344141365;7590.544567325757;7618.847623047397;7620.8475830481975;7668.309852113337;7362.6686799093095;10017.649382271586;10061.798764024532;10432.008959148869;10650.786984260116 09:21:41 INFO - PROCESS | 1672 | |3;mootools.html;1529.3882447021192;1538.4000239906438;1769.734539819027;1878.107898748094;1893.346795355602;6955.791326260208;7294.489385742987;8549.658013679453;8745.169208925157;8754.168354006375;8271.296939760128;8481.406301558893;8630.741077767678;8651.69719059833;8677.609507572159;2446.2049833803994;2453.116877923946;2460.052879641342;2470.110760126353;2470.197185914576;2425.7695518925675;2487.315988103268;2494.3764058985253;2497.7876880465183;2502.724700282967;2139.764625891151;2391.7369089400154;2438.207582535674;2456.2140115163156;2471.122751423246;2421.794147497465;2431.8905649245826;2453.0678342229926;2459.1270099114913;2463.408781892351;2445.4864478459544;2455.312512496504;2455.656208130863;2464.8397854139425;2474.2824580871525;8598;8622.180892815235;8633.438826476258;8647.481151130889;8691.34814888886;5305.7877684892555;5410.08028635129;5421.1326187810155;5463.836084917459;5468.371137319219;3656.2504686539196;3691.206390626028;3738.2710371477574;3744.6817020553285;3751.0622344413896;909.1817364372052;1046.4192373232852;1065.4193464561833;1079;1102.2339474065527;11508.597199098074;11596.59411920587;11602.12984026193;11602.535898564047;11637.254411838207;4129.97935010323;4826.32431459596;4850;4873.585745211626;4926.472793434047;9932.751681208028;9964.950175249078;10056.597736090474;10060.346077504939;10065.4464004479;5265.4997775211295;5290.788368465295;5299.3905700844125;5347.41178470368;5364.329458817648;4738.715677059387;4746.95253047472;4749.26386410107;4751.762411879392;4768.070226305837;3889.0666240102173;3945.408188771707;3985.561588225293;3988.4615576897327;3992.001999500125;996.4967691315984;1334.6129622409392;1335.9866401335862;1340.0753480098701;1340.9530666426629;1130.4460814200982;1132.8923752243525;1157.4328578996399;1284.9614511564669;1366.064245991499;7877.251661092187;7886.44794864354;7938.64276107565;8006.119326874039;8017.639206235734;1172.5661505243115;1173.436750359832;1189.6312143235625;1190.8451901252781;1214.5627574073326;6058.091286307089;6097.939020609826;6153.107799369066;6232.345603711636;6236.501079913687;10244.743881403025;10498.842517362245;10501.842472362921;10580.52387642558;10618.362898225976;10026.298159128792;10410.843837342445;10454.268201225994;10465.529051192714;10467.84298235527;8092.71675491355;8155.71454999084;8174.6730130795295;8194.262516373556;8257.38069644779;6052.969735151472;7536.6057279403485;7794.727184548514;7924.4056695746895;8022.478538894953;8348.33213342919;9048.457092574467;9110;9337.346385752931;9511.762205944906;9476.336656433983;9700.6119755209;9718.465484398426;9775.804483910424;9804.754881127743;7351.926480735338;7422.1835598085245;7446.70213191488;7467.77596672079;7477.327040566375;7964.123946366011;8185.754427367188;8195.385346098947;8204.58977051157;8210.261076503144;6956.652167391509;7715.537067775951;7781.2218778124;7884.2115788423;7892.329152021913;7396.96301518489;7433.591152486665;7466.813329666802;7541.396688265036;7567.545947243183;7553.471257011956;7611.809704757426;7618.961905190438;7644.847103058019;7650.387968962581;6770.086038384755;6928;6968.3728464438445;6977.232504424611;6978.232394436709;7027.859442811217;7091.60996145217;7097.503174777922;7110.3600675939415;7144.892826607709 09:21:41 INFO - PROCESS | 1672 | |4;prototype.html;325.51498267581303;329.23452971840464;330.3986744125692;335.3879170513813;336.6179386396439;6709.261981182066;6875.415589674883;6903.067141200466;6916.17005959285;7011.754588589407;6837.213720422153;6851.554648947827;6873.243943166261;6875.243723190445;6894.172699276094;4421.823127074913;4744.64415168862;4993.251012348141;5026.396832380119;5030.943501864613;4834.564889159975;5035;5051.2928190053235;5058.11482990477;5063.898722025558;3978.6220309070736;4890.413150421954;4897;4904.0437114762635;4923.77842997066;4909.189983652693;4917.434495033063;4920.532549407819;4933.777979990909;4953.529414705633;4153.397757325171;4173.895652608679;4184.28867092595;4198.265303571863;4202.2646036943715;6323.683815809191;6549.869002619921;6578.8355291118105;6605.867882642368;6638.336166383323;5852.151438041503;5867.941320586825;5870.530357571384;5884.146798714162;5884.176215329857;7407.592582407965;7418.035655364822;7419.443541734391;7421.8144546386775;7433.516821406592;4481.731096134243;4617.976910115428;4681.0871879983415;4693.014466961942;4709.952900470986;7479.775606731752;7698.653560589731;7720.961395192988;7724.995750552393;7768.689252429896;4723.834665786681;4741.786619602126;4747.667663263538;4753.835310348986;4754.096721622881;4758.468690022157;4813.061453016699;4831.323614693875;4840.322354870321;4856.660033797601;5815.5929084963645;5820.3888591698105;5824.854378640568;5856.267966504187;5859.472647461749;7333.413326933833;7337.0828646419195;7338.4496162788;7343.412526997828;7377.151627562899;1426.3153686230667;1480.7334679757746;1675.6229848284038;1687.8227786082284;1703.9829601703825;944.1738478831023;1047.2773786087575;1076.2842709598185;1081.810683130449;1088.4122573810055;6758.966205169041;6788.456923446112;6826.112605361272;6826.897596535957;6832.6925288362145;7550.697972081165;7607.809804754926;7621.580813055335;7674.079110506664;7695.115061767857;6553.836154096186;6741.865162696817;6752.662366881735;6792.898106528406;6844.691988860513;3251.219707270238;3928.901777455529;4064;4073.246449406869;4076.3681629347498;7899.0126234220725;7914.722984695508;7920.247576480225;7949.205079492004;7969.481983671043;6159.5072394209255;6453.354664533509;7848.254415830487;7905.209479052164;8001.239882211181;5816.854578635568;6951.374376306056;7941.6029198541;8175.427720059539;8217.506949583045;7393.8521229574035;7622.123455802432;7803.7268695595385;7810.921890781019;7862.095858976062;7646.15892251863;7686.154523002577;7702.114256860533;7710.614469276402;7729.458937874295;6612.437942774918;7891.605419729105;8137.959310203411;8154.2253485918745;8171.305439037511;3926.2736393767245;3986.621270979252;4850.53919877611;4871.123197824284;4873.512648735098;7748.883766743391;7768.417368697257;7779.883301750591;7781.883271751041;7802.21977802238;7336.156342020522;7512.361449276873;7513.549187048576;7515.173330933702;7533.7363192288;7491.625418729151;7527.360174385239;7531.133919599316;7533.058367704037;7543.245675432413;7301.963490182515;7331.633418329169;7352.77941661751;7360.631968401452;7362.153352364547;7288.234735352712;7510.812229694301;7517.699292028257;7522.322990930843;7534.962325188339;7308.926910730825;7335.963320183365;7339.8347487490255;7347.55914645123;7355.117385913724 09:21:41 INFO - PROCESS | 1672 | |5;yui.html;598.7425407074958;657.8059472455625;692.1797669761336;732.3079689693238;734.3574372424129;14942.701145977087;15016.849831501682;15113.924430377847;17126;17293.0488823115;15783.28975196116;15798.526044218692;15854.682906341866;15862.762058569131;15870.206489675527;684.5174152222696;842.6460886427715;853.3642436384871;862.7886167888876;865.2429124516049;857.3998201259112;857.7769779857219;863.7581477186367;868.9608967596441;869.0483920107504;879.6833140069601;887.1572006593761;904.6291020681522;906.0441234497603;907.5734404829719;887.6981826179131;890.6615486115268;895.632790555869;899.7705585075829;901.0268909577674;659.7361055577759;663.8904580744171;664.4651055899999;666.0575285970347;667.5227212543055;15225.467108651255;15227.390904363812;15290.770638440432;15293.464728734552;15301.540953771404;12562.623121306375;12609.180403273758;12678.619641410773;12840.743185136338;13163.341832908316;9208.585613647401;9920.107190352903;9998.55006524701;11149.99650031493;11950.940245298805;3401.268727223659;3458.291050334675;3475.7566970312087;3516.6659167379057;3521.366154092288;56434;59085;63043.68478157626;63072.05391919125;63223.68388158076;9215;9301.441913485212;9364.672236471826;9382.624695012122;9560.187384072431;19919.203231870564;19959.700604490943;20070.799292006894;20135.899320503595;20180.798192018185;7426.4430167736555;7449.366803821736;7609.733659322008;7768.572728499874;7977.162397948132;4034.9394759078637;4085.632293093636;4116.217918595457;4171.687123465692;4325.675574331937;3517.43721004638;3560.9851192410038;3562.7684200526883;3566.964330356715;3574.946375804365;661.0843981086134;663.8041777675501;668.2949488289804;674.9628770417576;683.5044592670374;568.0286709726414;570.1960236067125;589.4871461828237;597.9760809567568;624.2384291164772;7918.683252669829;8739.91260087391;8990.730278091667;9074.546272686339;9132.22376098039;508.5905845794097;510.9693418394908;535.7883635963773;559.381883019266;565.1974196640698;3762.698984081267;3938.6455219030427;3979.303621866162;4064.1058967027207;4081.97959010209;94240.58639120418;94949;96474;96595;96656;79978.20021799824;81055;81075;81324.59337703274;81605.77591336134;68222;69135;70499.99999999898;70867;70889;8015.879761803461;8307.293880020266;8351.749447516584;8709.564521773758;8767.298616110824;38863;45874.77062614666;51974.740126299126;52074.147394943706;52251.216231755796;56803.295901123034;63001.68499157475;71085.64457177887;71368.64315678389;71414;8769.517676527852;11251.437428128396;11360.318380896842;11673.708157296136;12399.504019839465;11700.648980530597;12027.518899243933;12231.388430578614;12311.261324320569;12717.745645087232;9256.028117047883;10297.867234604038;11054.723631909266;11333.603323883624;11427;9832.410055396698;11246.550137994389;11314.660560183207;11404.771904562029;11500.482478288664;10831.51258193399;11554.537818487166;11735.823962640738;11738.70653233676;11767.823217678164;9373.906260937576;9429.905700943178;9592.952035240058;9903.50482475859;10101.191904647463;7622.656980435783;8006.439549231498;8644.610992505226;9434.858477122985;10103.191744660557 09:21:41 INFO - PROCESS | 1672 | __end_tp_report 09:21:41 INFO - PROCESS | 1672 | __start_cc_report 09:21:41 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,373 09:21:41 INFO - PROCESS | 1672 | __end_cc_report 09:21:41 INFO - PROCESS | 1672 | __startTimestamp1455211301195__endTimestamp 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | ------- Summary: start ------- 09:21:41 INFO - PROCESS | 1672 | Number of tests: 6 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5717.08 Median:6082.64 stddev:802.29 (13.2%) stddev-sans-first:773.43 09:21:41 INFO - PROCESS | 1672 | Values: 2887.2 3329.6 3370.5 3579.2 3607.3 4458.4 5235.4 5766.9 5799.2 5809.7 5642.9 5743.4 5771.9 5774.0 5955.0 5139.8 5209.8 5228.6 5249.2 5285.3 72.0 5123.2 5185.1 5199.6 5228.7 5182.9 5200.7 5201.7 5214.4 5222.7 5181.6 5200.0 5205.2 5222.4 5259.1 5089.1 5116.2 5187.5 5196.9 5246.3 5592.8 5621.7 5679.9 5759.1 5768.9 6092.5 6189.0 6314.0 6315.3 6347.3 6294.7 6310.1 6322.4 6324.8 6368.7 5867.4 5876.6 5879.1 5903.4 5939.6 5324.3 6197.7 6235.0 6303.8 6319.9 6221.8 6237.6 6244.8 6245.0 6249.8 5906.6 6152.0 6233.1 6269.6 6290.7 6131.5 6162.2 6190.9 6285.3 6291.6 6228.9 6285.2 6297.5 6320.7 6324.7 4811.4 4891.8 4929.5 4939.9 4983.0 4391.1 4406.2 4412.8 4425.8 4429.2 5548.9 5581.7 5585.7 5617.7 5625.8 6159.5 6160.7 6175.9 6216.8 6338.1 4797.4 5747.7 5757.5 5781.3 5801.3 5279.6 5388.2 5401.2 5416.6 5429.2 6302.8 6325.1 6328.9 6335.7 6352.1 6263.3 6283.6 6323.6 6336.1 6347.2 6188.3 6277.0 6289.9 6318.6 6321.5 5515.4 6210.8 6251.2 6284.5 6288.1 6280.0 6284.6 6321.9 6323.7 6338.2 6062.9 6083.3 6133.0 6157.2 6168.1 6143.3 6149.7 6160.0 6169.8 6215.1 5932.5 5989.7 6098.1 6117.2 6118.3 6076.1 6142.2 6173.5 6174.5 6192.2 6053.8 6082.6 6094.8 6100.1 6109.8 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:64632.25 Median:38320.93 stddev:136635.70 (356.6%) stddev-sans-first:136934.02 09:21:41 INFO - PROCESS | 1672 | Values: 449.1 458.0 470.7 556.7 610.5 35498.1 37827.8 37843.1 37892.0 37911.9 39123.6 39582.0 39643.2 39698.2 39733.4 9382.0 9580.3 9621.5 9627.1 9635.5 7153.3 9169.3 9367.8 9595.5 9617.6 9598.0 9638.6 9650.2 9652.9 9705.2 9579.7 9603.0 9609.1 9628.7 9650.1 9530.7 9623.6 9626.4 9634.0 9675.8 37816.8 38315.0 38326.8 38374.0 38441.4 3204.8 3359.4 3563.4 3703.9 3734.6 28206.7 28377.3 28395.9 29333.3 29560.0 2811.9 2836.6 2848.5 2861.4 2890.8 485991.0 491340.1 491972.0 496569.0 496657.0 44826.8 45389.5 67359.7 67900.0 67902.7 44140.6 44208.3 44475.0 44580.7 44736.8 15104.8 15207.0 15231.8 15302.9 15322.7 11918.3 12166.2 12183.1 12237.4 12323.8 14347.0 14367.6 14613.3 14708.5 14830.5 6250.0 6365.6 6381.0 6400.7 6407.9 930.1 955.0 1026.2 1058.5 1085.8 29903.9 30066.2 30174.0 30264.5 30464.8 1089.9 1094.6 1126.4 1162.0 1192.4 13392.0 14352.9 15153.9 15188.1 15447.6 537435.0 538641.6 542733.0 544103.0 544542.0 45746.5 56172.7 57033.0 57212.1 59321.7 1389.8 1404.6 1406.3 1417.6 1435.7 28606.3 29467.6 29683.7 30033.8 30149.4 51342.0 51668.0 51894.7 51963.5 51973.2 499708.0 502385.5 505094.0 505723.0 506152.0 33074.5 43243.8 44142.8 44683.9 44738.8 42286.7 42627.4 42731.6 43301.4 43868.6 35469.1 37163.3 37592.9 37752.6 37905.6 36341.5 36519.8 36776.0 36843.8 37122.9 36019.0 37027.4 37028.3 37345.0 37488.3 30792.4 33226.7 33402.7 33463.3 33524.3 24687.1 25389.7 25839.4 25940.7 26340.3 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:37682.65 Median:1442.25 stddev:125834.94 (8724.9%) stddev-sans-first:126156.70 09:21:41 INFO - PROCESS | 1672 | Values: 343.8 363.3 365.4 367.8 371.1 15568.5 18771.3 19291.6 19375.9 20034.1 11503.7 14446.3 18207.8 18868.8 18965.7 3918.0 5045.8 5063.9 5321.1 5322.2 5840.2 5909.7 5944.5 5945.3 5955.0 5894.8 6305.0 6358.3 6374.3 6381.8 5797.7 5908.5 5912.1 6310.7 6325.1 5586.8 5640.9 5648.6 5648.7 5655.9 13248.7 13534.3 13604.8 13618.8 24803.8 1993.1 2105.7 2153.8 2403.6 2540.7 9516.2 9710.5 10048.5 12069.8 14037.9 1781.9 1829.2 1841.3 1864.2 1871.5 75704.0 94954.1 100455.5 101851.5 104199.0 10795.5 11064.2 11216.7 11252.4 11327.0 11658.8 13174.5 13186.7 13203.6 13227.0 6768.9 7909.5 8300.8 8350.7 8375.9 8330.7 8357.4 8462.0 8499.1 8558.0 4034.6 4277.7 4444.6 4444.6 4459.2 1880.1 1896.3 1898.5 1902.2 1905.0 760.5 827.3 902.8 985.9 987.1 19800.0 20659.0 20738.1 20869.0 20869.7 908.5 971.1 977.7 1011.3 1030.7 9290.9 10608.4 10918.6 10960.3 11009.1 754049.0 758174.0 761697.0 774228.0 774868.0 111093.3 115858.4 116690.0 117511.8 118165.4 1199.2 1202.8 1254.4 1260.7 1306.8 18411.1 18512.1 18721.0 18831.6 18904.9 44947.0 48915.8 50302.0 50548.7 50661.0 69141.7 69807.3 71575.6 72584.5 72949.6 5507.8 5767.9 6304.7 7486.3 7708.0 9160.7 11351.1 11772.4 12079.0 12195.9 10128.5 10311.9 10537.2 10542.1 10604.0 9623.8 9707.5 9723.1 10245.3 10405.6 8450.8 10312.8 10658.6 10747.2 10766.0 7531.4 7590.5 7618.8 7620.8 7668.3 7362.7 10017.6 10061.8 10432.0 10650.8 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5974.78 Median:5355.87 stddev:3099.18 (57.9%) stddev-sans-first:3089.86 09:21:41 INFO - PROCESS | 1672 | Values: 1529.4 1538.4 1769.7 1878.1 1893.3 6955.8 7294.5 8549.7 8745.2 8754.2 8271.3 8481.4 8630.7 8651.7 8677.6 2446.2 2453.1 2460.1 2470.1 2470.2 2425.8 2487.3 2494.4 2497.8 2502.7 2139.8 2391.7 2438.2 2456.2 2471.1 2421.8 2431.9 2453.1 2459.1 2463.4 2445.5 2455.3 2455.7 2464.8 2474.3 8598.0 8622.2 8633.4 8647.5 8691.3 5305.8 5410.1 5421.1 5463.8 5468.4 3656.3 3691.2 3738.3 3744.7 3751.1 909.2 1046.4 1065.4 1079.0 1102.2 11508.6 11596.6 11602.1 11602.5 11637.3 4130.0 4826.3 4850.0 4873.6 4926.5 9932.8 9965.0 10056.6 10060.3 10065.4 5265.5 5290.8 5299.4 5347.4 5364.3 4738.7 4747.0 4749.3 4751.8 4768.1 3889.1 3945.4 3985.6 3988.5 3992.0 996.5 1334.6 1336.0 1340.1 1341.0 1130.4 1132.9 1157.4 1285.0 1366.1 7877.3 7886.4 7938.6 8006.1 8017.6 1172.6 1173.4 1189.6 1190.8 1214.6 6058.1 6097.9 6153.1 6232.3 6236.5 10244.7 10498.8 10501.8 10580.5 10618.4 10026.3 10410.8 10454.3 10465.5 10467.8 8092.7 8155.7 8174.7 8194.3 8257.4 6053.0 7536.6 7794.7 7924.4 8022.5 8348.3 9048.5 9110.0 9337.3 9511.8 9476.3 9700.6 9718.5 9775.8 9804.8 7351.9 7422.2 7446.7 7467.8 7477.3 7964.1 8185.8 8195.4 8204.6 8210.3 6956.7 7715.5 7781.2 7884.2 7892.3 7397.0 7433.6 7466.8 7541.4 7567.5 7553.5 7611.8 7619.0 7644.8 7650.4 6770.1 6928.0 6968.4 6977.2 6978.2 7027.9 7091.6 7097.5 7110.4 7144.9 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5988.86 Median:6826.51 stddev:1982.20 (29.0%) stddev-sans-first:1941.64 09:21:41 INFO - PROCESS | 1672 | Values: 325.5 329.2 330.4 335.4 336.6 6709.3 6875.4 6903.1 6916.2 7011.8 6837.2 6851.6 6873.2 6875.2 6894.2 4421.8 4744.6 4993.3 5026.4 5030.9 4834.6 5035.0 5051.3 5058.1 5063.9 3978.6 4890.4 4897.0 4904.0 4923.8 4909.2 4917.4 4920.5 4933.8 4953.5 4153.4 4173.9 4184.3 4198.3 4202.3 6323.7 6549.9 6578.8 6605.9 6638.3 5852.2 5867.9 5870.5 5884.1 5884.2 7407.6 7418.0 7419.4 7421.8 7433.5 4481.7 4618.0 4681.1 4693.0 4710.0 7479.8 7698.7 7721.0 7725.0 7768.7 4723.8 4741.8 4747.7 4753.8 4754.1 4758.5 4813.1 4831.3 4840.3 4856.7 5815.6 5820.4 5824.9 5856.3 5859.5 7333.4 7337.1 7338.4 7343.4 7377.2 1426.3 1480.7 1675.6 1687.8 1704.0 944.2 1047.3 1076.3 1081.8 1088.4 6759.0 6788.5 6826.1 6826.9 6832.7 7550.7 7607.8 7621.6 7674.1 7695.1 6553.8 6741.9 6752.7 6792.9 6844.7 3251.2 3928.9 4064.0 4073.2 4076.4 7899.0 7914.7 7920.2 7949.2 7969.5 6159.5 6453.4 7848.3 7905.2 8001.2 5816.9 6951.4 7941.6 8175.4 8217.5 7393.9 7622.1 7803.7 7810.9 7862.1 7646.2 7686.2 7702.1 7710.6 7729.5 6612.4 7891.6 8138.0 8154.2 8171.3 3926.3 3986.6 4850.5 4871.1 4873.5 7748.9 7768.4 7779.9 7781.9 7802.2 7336.2 7512.4 7513.5 7515.2 7533.7 7491.6 7527.4 7531.1 7533.1 7543.2 7302.0 7331.6 7352.8 7360.6 7362.2 7288.2 7510.8 7517.7 7522.3 7535.0 7308.9 7336.0 7339.8 7347.6 7355.1 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17885.13 Median:63022.68 stddev:24937.47 (39.6%) stddev-sans-first:24973.65 09:21:41 INFO - PROCESS | 1672 | Values: 598.7 657.8 692.2 732.3 734.4 14942.7 15016.8 15113.9 17126.0 17293.0 15783.3 15798.5 15854.7 15862.8 15870.2 684.5 842.6 853.4 862.8 865.2 857.4 857.8 863.8 869.0 869.0 879.7 887.2 904.6 906.0 907.6 887.7 890.7 895.6 899.8 901.0 659.7 663.9 664.5 666.1 667.5 15225.5 15227.4 15290.8 15293.5 15301.5 12562.6 12609.2 12678.6 12840.7 13163.3 9208.6 9920.1 9998.6 11150.0 11950.9 3401.3 3458.3 3475.8 3516.7 3521.4 56434.0 59085.0 63043.7 63072.1 63223.7 9215.0 9301.4 9364.7 9382.6 9560.2 19919.2 19959.7 20070.8 20135.9 20180.8 7426.4 7449.4 7609.7 7768.6 7977.2 4034.9 4085.6 4116.2 4171.7 4325.7 3517.4 3561.0 3562.8 3567.0 3574.9 661.1 663.8 668.3 675.0 683.5 568.0 570.2 589.5 598.0 624.2 7918.7 8739.9 8990.7 9074.5 9132.2 508.6 511.0 535.8 559.4 565.2 3762.7 3938.6 3979.3 4064.1 4082.0 94240.6 94949.0 96474.0 96595.0 96656.0 79978.2 81055.0 81075.0 81324.6 81605.8 68222.0 69135.0 70500.0 70867.0 70889.0 8015.9 8307.3 8351.7 8709.6 8767.3 38863.0 45874.8 51974.7 52074.1 52251.2 56803.3 63001.7 71085.6 71368.6 71414.0 8769.5 11251.4 11360.3 11673.7 12399.5 11700.6 12027.5 12231.4 12311.3 12717.7 9256.0 10297.9 11054.7 11333.6 11427.0 9832.4 11246.6 11314.7 11404.8 11500.5 10831.5 11554.5 11735.8 11738.7 11767.8 9373.9 9429.9 9593.0 9903.5 10101.2 7622.7 8006.4 8644.6 9434.9 10103.2 09:21:41 INFO - PROCESS | 1672 | -------- Summary: end -------- 09:21:41 INFO - PROCESS | 1672 | 09:21:41 INFO - TEST-INFO | 1672: exit 0 09:21:41 INFO - TEST-OK | dromaeo_css | took 1109399ms 09:21:41 INFO - TEST-START | kraken 09:21:41 INFO - operating with platform_type : mac_ 09:21:41 INFO - Initialising browser for kraken test... 09:21:41 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpzACvJX/profile http://localhost:49274/getInfo.html) 09:21:44 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 09:21:44 INFO - PROCESS | 1683 | colorDepth:24 09:21:44 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 09:21:44 INFO - PROCESS | 1683 | __metrics 09:21:44 INFO - PROCESS | 1683 | 09:21:44 INFO - PROCESS | 1683 | ###!!! [Child][MessageChannel] Error: (msgtype=0x2A0045,name=PBrowser::Msg___delete__) Channel closing: too late to send/recv, messages will be lost 09:21:44 INFO - PROCESS | 1683 | 09:21:45 INFO - TEST-INFO | 1683: exit 0 09:21:45 INFO - Browser initialized. 09:21:45 INFO - Running cycle 1/1 for kraken test... 09:21:45 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpzACvJX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:57 INFO - PROCESS | 1686 | RSS: Main: 257384448 09:21:57 INFO - PROCESS | 1686 | 09:21:59 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:00 INFO - PROCESS | 1686 | RSS: Main: 270385152 09:22:00 INFO - PROCESS | 1686 | 09:22:03 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 09:22:03 INFO - PROCESS | 1686 | RSS: Main: 278147072 09:22:03 INFO - PROCESS | 1686 | 09:22:06 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 09:22:07 INFO - PROCESS | 1686 | RSS: Main: 282488832 09:22:07 INFO - PROCESS | 1686 | 09:22:09 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 09:22:10 INFO - PROCESS | 1686 | RSS: Main: 284778496 09:22:10 INFO - PROCESS | 1686 | 09:22:12 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:12 INFO - PROCESS | 1686 | RSS: Main: 285388800 09:22:12 INFO - PROCESS | 1686 | 09:22:15 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:16 INFO - PROCESS | 1686 | RSS: Main: 284803072 09:22:16 INFO - PROCESS | 1686 | 09:22:19 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:19 INFO - PROCESS | 1686 | RSS: Main: 284897280 09:22:19 INFO - PROCESS | 1686 | 09:22:22 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 09:22:23 INFO - PROCESS | 1686 | RSS: Main: 285470720 09:22:23 INFO - PROCESS | 1686 | 09:22:25 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:25 INFO - PROCESS | 1686 | RSS: Main: 285536256 09:22:25 INFO - PROCESS | 1686 | 09:22:27 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:28 INFO - PROCESS | 1686 | RSS: Main: 286101504 09:22:28 INFO - PROCESS | 1686 | 09:22:30 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:30 INFO - PROCESS | 1686 | RSS: Main: 285863936 09:22:30 INFO - PROCESS | 1686 | 09:22:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:34 INFO - PROCESS | 1686 | RSS: Main: 286064640 09:22:34 INFO - PROCESS | 1686 | 09:22:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:37 INFO - PROCESS | 1686 | RSS: Main: 284651520 09:22:37 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 09:22:39 INFO - PROCESS | 1686 | RSS: Main: 287301632 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | __start_tp_report 09:22:39 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 09:22:39 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 09:22:39 INFO - PROCESS | 1686 | |i|pagename|runs| 09:22:39 INFO - PROCESS | 1686 | |0;ai-astar;114;104;114;113;117;126;113;118;166;115 09:22:39 INFO - PROCESS | 1686 | |1;audio-beat-detection;150;123;143;149;124;140;140;121;154;122 09:22:39 INFO - PROCESS | 1686 | |2;audio-dft;200;203;194;212;200;209;210;197;235;227 09:22:39 INFO - PROCESS | 1686 | |3;audio-fft;77;73;73;109;56;57;59;60;116;78 09:22:39 INFO - PROCESS | 1686 | |4;audio-oscillator;81;86;122;79;82;86;127;83;82;114 09:22:39 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;86;106;86;87;128;139;86;101;86;94 09:22:39 INFO - PROCESS | 1686 | |6;imaging-darkroom;95;96;106;118;120;123;116;144;151;151 09:22:39 INFO - PROCESS | 1686 | |7;imaging-desaturate;91;92;91;93;93;108;145;105;87;150 09:22:39 INFO - PROCESS | 1686 | |8;json-parse-financial;52;83;83;46;48;59;85;90;84;79 09:22:39 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;65;67;70;71;73;72;68;66;66;66 09:22:39 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;85;86;114;101;86;90;88;89;84;87 09:22:39 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;117;138;142;130;140;145;114;139;137;133 09:22:39 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;152;141;129;177;177;141;138;140;151;181 09:22:39 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;72;99;66;60;60;59;59;98;61;89 09:22:39 INFO - PROCESS | 1686 | __end_tp_report 09:22:39 INFO - PROCESS | 1686 | __start_cc_report 09:22:39 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,925 09:22:39 INFO - PROCESS | 1686 | __end_cc_report 09:22:39 INFO - PROCESS | 1686 | __startTimestamp1455211359414__endTimestamp 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | ------- Summary: start ------- 09:22:39 INFO - PROCESS | 1686 | Number of tests: 14 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:120.00 Median:116.00 stddev:17.05 (14.7%) stddev-sans-first:17.94 09:22:39 INFO - PROCESS | 1686 | Values: 114.0 104.0 114.0 113.0 117.0 126.0 113.0 118.0 166.0 115.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:141.50 stddev:12.91 (9.1%) stddev-sans-first:12.75 09:22:39 INFO - PROCESS | 1686 | Values: 150.0 123.0 143.0 149.0 124.0 140.0 140.0 121.0 154.0 122.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:208.70 Median:209.50 stddev:13.23 (6.3%) stddev-sans-first:13.66 09:22:39 INFO - PROCESS | 1686 | Values: 200.0 203.0 194.0 212.0 200.0 209.0 210.0 197.0 235.0 227.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:75.80 Median:66.50 stddev:21.13 (31.8%) stddev-sans-first:22.41 09:22:39 INFO - PROCESS | 1686 | Values: 77.0 73.0 73.0 109.0 56.0 57.0 59.0 60.0 116.0 78.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:82.00 stddev:18.87 (23.0%) stddev-sans-first:19.40 09:22:39 INFO - PROCESS | 1686 | Values: 81.0 86.0 122.0 79.0 82.0 86.0 127.0 83.0 82.0 114.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:99.90 Median:86.00 stddev:19.24 (22.4%) stddev-sans-first:19.74 09:22:39 INFO - PROCESS | 1686 | Values: 86.0 106.0 86.0 87.0 128.0 139.0 86.0 101.0 86.0 94.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:122.00 Median:147.50 stddev:20.77 (14.1%) stddev-sans-first:19.60 09:22:39 INFO - PROCESS | 1686 | Values: 95.0 96.0 106.0 118.0 120.0 123.0 116.0 144.0 151.0 151.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:91.00 stddev:23.10 (25.4%) stddev-sans-first:23.90 09:22:39 INFO - PROCESS | 1686 | Values: 91.0 92.0 91.0 93.0 93.0 108.0 145.0 105.0 87.0 150.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:70.90 Median:83.00 stddev:17.44 (21.0%) stddev-sans-first:17.10 09:22:39 INFO - PROCESS | 1686 | Values: 52.0 83.0 83.0 46.0 48.0 59.0 85.0 90.0 84.0 79.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:69.00 stddev:2.88 (4.2%) stddev-sans-first:2.77 09:22:39 INFO - PROCESS | 1686 | Values: 65.0 67.0 70.0 71.0 73.0 72.0 68.0 66.0 66.0 66.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:86.50 stddev:9.39 (10.9%) stddev-sans-first:9.71 09:22:39 INFO - PROCESS | 1686 | Values: 85.0 86.0 114.0 101.0 86.0 90.0 88.0 89.0 84.0 87.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:133.50 Median:138.50 stddev:10.41 (7.5%) stddev-sans-first:9.17 09:22:39 INFO - PROCESS | 1686 | Values: 117.0 138.0 142.0 130.0 140.0 145.0 114.0 139.0 137.0 133.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:151.50 stddev:18.85 (12.4%) stddev-sans-first:19.99 09:22:39 INFO - PROCESS | 1686 | Values: 152.0 141.0 129.0 177.0 177.0 141.0 138.0 140.0 151.0 181.0 09:22:39 INFO - PROCESS | 1686 | 09:22:39 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:69.00 stddev:16.59 (24.0%) stddev-sans-first:17.59 09:22:39 INFO - PROCESS | 1686 | Values: 72.0 99.0 66.0 60.0 60.0 59.0 59.0 98.0 61.0 89.0 09:22:39 INFO - PROCESS | 1686 | -------- Summary: end -------- 09:22:39 INFO - PROCESS | 1686 | 09:22:40 INFO - TEST-INFO | 1686: exit 0 09:22:40 INFO - TEST-OK | kraken | took 58409ms 09:22:40 INFO - SUITE-END | took 1167s 09:22:40 INFO - Completed test suite (00:19:28) 09:22:40 INFO - Working with test: dromaeo_css 09:22:40 INFO - Generating results file: dromaeo_css 09:22:40 INFO - Working with test: kraken 09:22:40 INFO - Generating results file: kraken 09:22:40 INFO - javascript benchmark 09:22:40 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:40 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,61]] 09:22:40 INFO - process_Request line: dromaeo_css 7484.13 graph.html#tests=[[72,64,61]] 09:22:40 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:41 INFO - process_Request line: kraken 1551.50 graph.html#tests=[[232,64,61]] 09:22:41 INFO - RETURN: dromaeo_css: 7484.13 09:22:41 INFO - RETURN: kraken: 1551.50 09:22:41 INFO - javascript benchmark 09:22:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2887.2348827560695, 3329.6015673417164, 3370.511275865, 3579.2125732338877, 3607.2965771674517, 4458.4426946631675, 5235.371755389349, 5766.942330576699, 5799.246098007258, 5809.709514524278, 5642.858928526779, 5743.368229494752, 5771.855703607412, 5774.0, 5955.0, 5139.845804625857, 5209.843704688866, 5228.5555727763185, 5249.186376111708, 5285.312909321783, 71.99603399009992, 5123.2315152727015, 5185.118529849935, 5199.610029247802, 5228.686278823283, 5182.896342073156, 5200.68795872247, 5201.713905735183, 5214.400343960455, 5222.738863056851, 5181.559567436772, 5199.974000130013, 5205.193195054772, 5222.373315202162, 5259.053370393328, 5089.134847076006, 5116.232565115225, 5187.455317191716, 5196.870078248036, 5246.2655228267695, 5592.7762889484375, 5621.747021384048, 5679.886402271932, 5759.078547432391, 5768.913466298009, 6092.482139018144, 6188.9690551546955, 6313.96843015782, 6315.305316415191, 6347.301796802348, 6294.716737746812, 6310.148130002437, 6322.367763223687, 6324.849551152584, 6368.713407896656, 5867.413258674141, 5876.61801982874, 5879.088741245114, 5903.439173278532, 5939.643621382731, 5324.334458192726, 6197.721102550306, 6234.9688251558455, 6303.779367722108, 6319.9368006320265, 6221.813345599639, 6237.5945563539135, 6244.812655620339, 6245.0, 6249.78125765596, 5906.645601263938, 6151.954167791486, 6233.096201050913, 6269.561130720897, 6290.748370065237, 6131.468419844996, 6162.22972128484, 6190.916589596803, 6285.340039295899, 6291.622502649856, 6228.906566401508, 6285.2457705075685, 6297.527685423612, 6320.683965801692, 6324.659875845967, 4811.350467686888, 4891.804327826847, 4929.482404347564, 4939.851804445872, 4982.950170498321, 4391.121775644884, 4406.228909940747, 4412.757298348622, 4425.845095421645, 4429.202743506135, 5548.889022219614, 5581.6930068845795, 5585.692786896679, 5617.662940223599, 5625.803096891589, 6159.538034647419, 6160.661163636043, 6175.938240617626, 6216.844578885473, 6338.080978258126, 4797.376341075638, 5747.655140691572, 5757.510611598061, 5781.335146458127, 5801.3328467225965, 5279.630425870229, 5388.164834450667, 5401.243825864382, 5416.647917885403, 5429.158480435539, 6302.779402720883, 6325.051242313598, 6328.860805055135, 6335.714892829741, 6352.142460767737, 6263.311035786151, 6283.560150789401, 6323.588966717149, 6336.08126821599, 6347.238331400262, 6188.257409110755, 6276.968615157078, 6289.930711778917, 6318.62088274705, 6321.462675672436, 5515.393306736336, 6210.759540939494, 6251.249850018027, 6284.497240220863, 6288.0882272069375, 6279.96860015697, 6284.591501552384, 6321.873562528632, 6323.715432805443, 6338.207724034496, 6062.909056364247, 6083.300420451705, 6132.969335153296, 6157.168782214344, 6168.074788781719, 6143.341317722334, 6149.692515374124, 6159.952808022557, 6169.784057558143, 6215.06773983906, 5932.525397968238, 5989.730462129128, 6098.054801505774, 6117.174181485442, 6118.326984031842, 6076.1493390924425, 6142.232220972379, 6173.492821055704, 6174.536909731698, 6192.225971753531, 6053.804979294312, 6082.635041897501, 6094.8171554853425, 6100.115483254992, 6109.816705498843], "unit": "score", "name": "dojo.html", "value": 5666.597055538983}, {"lowerIsBetter": false, "replicates": [449.05922093214724, 457.97481138537376, 470.7128651522573, 556.6945512772548, 610.5481943361908, 35498.112547186334, 37827.810860945654, 37843.053923651925, 37892.0, 37911.86264412069, 39123.60876391235, 39582.0, 39643.207135857265, 39698.206035879266, 39733.40399894004, 9382.0148884367, 9580.281478889076, 9621.470819104947, 9627.085426884434, 9635.470049147294, 7153.284671532857, 9169.312301577374, 9367.765805854873, 9595.472249026301, 9617.56720947559, 9597.992210817869, 9638.614455421775, 9650.179734722557, 9652.938176800546, 9705.175060119898, 9579.712608621752, 9603.0, 9609.13517783396, 9628.662996795114, 9650.08324209199, 9530.666426675103, 9623.56693948774, 9626.422414655073, 9633.988431214762, 9675.758106047335, 37816.81091594524, 38315.04212394685, 38326.80836595827, 38374.040648983995, 38441.42337864934, 3204.839758012113, 3359.445691460906, 3563.376409128418, 3703.9444408333898, 3734.5705243897028, 28206.71793282082, 28377.290567735767, 28395.858020709766, 29333.26666833325, 29559.96540121107, 2811.8734656940283, 2836.5886946392657, 2848.458792829356, 2861.370498490329, 2890.7976441649093, 485991.0, 491340.08659913304, 491972.0, 496569.0, 496656.9999999964, 44826.77586612046, 45389.54610453853, 67359.66320168368, 67899.98150027753, 67902.66048669824, 44140.55859441365, 44208.3368749469, 44475.0, 44580.66258012265, 44736.77631611821, 15104.773428398583, 15207.0, 15231.847681523264, 15302.923485382502, 15322.693546129016, 11918.284902905853, 12166.209196402206, 12183.147179697513, 12237.449314780857, 12323.815142772866, 14347.0, 14367.568972930829, 14613.26933653313, 14708.485203017843, 14830.480933167288, 6250.0, 6365.586236894587, 6381.0, 6400.743970241231, 6407.935920640734, 930.0559931669361, 954.9856752148723, 1026.2149455666422, 1058.5342449322427, 1085.8045551800594, 29903.850480747456, 30066.248343791143, 30174.0, 30264.54603180954, 30464.84767576148, 1089.8855620159927, 1094.5567045346488, 1126.4311522680994, 1161.9593314233962, 1192.43359404284, 13392.0, 14352.851771858226, 15153.924230378998, 15188.088714677155, 15447.613809654624, 537435.0, 538641.613583867, 542733.0, 544103.0, 544542.0, 45746.542534574895, 56172.71913640487, 57033.0, 57212.14181787276, 59321.70339148363, 1389.7637401641746, 1404.6347949533194, 1406.2617126008722, 1417.5534706567605, 1435.7415665180158, 28606.284842879093, 29467.55798662979, 29683.70316296896, 30033.849830750707, 30149.397012060075, 51342.0, 51668.0, 51894.74052629713, 51963.480365195865, 51973.22040169325, 499708.0, 502385.4880725719, 505094.0, 505723.0, 506152.0, 33074.5038824413, 43243.78378108089, 44142.77928610337, 44683.88290292639, 44738.77630611826, 42286.73139805811, 42627.360589590564, 42731.57268427276, 43301.35047974346, 43868.56131438645, 35469.113272168404, 37163.25673486569, 37592.87221383363, 37752.62247377491, 37905.62094379021, 36341.45487817738, 36519.81740091283, 36776.0, 36843.81578092092, 37122.88631341064, 36019.0, 37027.372932644386, 37028.259434810614, 37345.0, 37488.2502349946, 30792.384152317278, 33226.6709331624, 33402.665973340925, 33463.33073338471, 33524.329513410084, 24687.135950241656, 25389.74610253874, 25839.354016149748, 25940.740592593833, 26340.341491462867], "unit": "score", "name": "ext.html", "value": 19265.684141078782}, {"lowerIsBetter": false, "replicates": [343.8109040027984, 363.25895173845356, 365.37520839364686, 367.77381910125274, 371.10193332136225, 15568.45510407136, 18771.343002994898, 19291.614167716656, 19375.903120484414, 20034.098465569066, 11503.712407189803, 14446.277686115705, 18207.817921820777, 18868.81131188688, 18965.715514267296, 3918.0009097680195, 5045.8486245412605, 5063.949360506384, 5321.122014867542, 5322.2282768998475, 5840.211571437865, 5909.7340619672, 5944.524438044968, 5945.316288626798, 5955.0, 5894.793682221101, 6305.0, 6358.268799088095, 6374.298827129035, 6381.7766378176775, 5797.739101740432, 5908.497777688902, 5912.142739302777, 6310.747570097191, 6325.051242313643, 5586.832395028156, 5640.887182256373, 5648.63283886546, 5648.661080335193, 5655.943440565583, 13248.73502529944, 13534.335692508193, 13604.795928061087, 13618.795718064237, 24803.751962480324, 1993.132987150594, 2105.6841473778904, 2153.838462115332, 2403.644846768875, 2540.695116586003, 9516.23870090391, 9710.514474276259, 10048.547815348327, 12069.758604827854, 14037.947153963494, 1781.910904454772, 1829.2225804033367, 1841.254292011724, 1864.2449807827852, 1871.4853415310763, 75704.0, 94954.0504594952, 100455.49772251165, 101851.4722279159, 104199.0, 10795.514201860935, 11064.17018723599, 11216.663500094846, 11252.43737813106, 11326.980571748581, 11658.766824663458, 13174.538891138764, 13186.670333241555, 13203.603891883258, 13227.0, 6768.864622707519, 7909.52542847431, 8300.792480187924, 8350.749477515563, 8375.916240837636, 8330.666773329, 8357.373197010249, 8462.0, 8499.065102838682, 8558.0, 4034.5561988181275, 4277.743335399824, 4444.622207112367, 4444.644428445717, 4459.152760975404, 1880.125741530195, 1896.3268039845932, 1898.4589392023483, 1902.172554938606, 1904.990355111793, 760.5284723471483, 827.2637352756052, 902.7697937026147, 985.9161971232526, 987.1362557761959, 19800.00999949997, 20659.0, 20738.066786994623, 20869.0, 20869.68695469569, 908.5321059654283, 971.1162841813915, 977.7117384843982, 1011.3476807459148, 1030.7320096774893, 9290.860637090314, 10608.41653709038, 10918.563257469772, 10960.342379457257, 11009.119270458345, 754049.0, 758174.0, 761697.0, 774228.0, 774868.0, 111093.33359999607, 115858.4207078976, 116690.0, 117511.8248817518, 118165.40917295359, 1199.1665792274287, 1202.7895118354254, 1254.4417734108417, 1260.6785269756153, 1306.764782339188, 18411.079446027376, 18512.0743962804, 18721.0, 18831.623367532848, 18904.905475472533, 44947.0, 48915.75542122267, 50301.993960119864, 50548.74725626349, 50661.0, 69141.65429173023, 69807.30192698009, 71575.64212178906, 72584.54830903458, 72949.63525182553, 5507.779688812403, 5767.884642307215, 6304.684765761602, 7486.326230639269, 7708.0, 9160.6793762218, 11351.091912646803, 11772.411379430823, 12079.0, 12195.898865220272, 10128.544215510172, 10311.896881031094, 10537.157027437634, 10542.103921166787, 10604.045635892806, 9623.807523849624, 9707.514624268899, 9723.076307750753, 10245.334053286513, 10405.635802746867, 8450.830983380421, 10312.845307320245, 10658.62694805678, 10747.193960452842, 10766.0, 7531.3598344141365, 7590.544567325757, 7618.847623047397, 7620.8475830481975, 7668.309852113337, 7362.6686799093095, 10017.649382271586, 10061.798764024532, 10432.008959148869, 10650.786984260116], "unit": "score", "name": "jquery.html", "value": 9069.534092305617}, {"lowerIsBetter": false, "replicates": [1529.3882447021192, 1538.4000239906438, 1769.734539819027, 1878.107898748094, 1893.346795355602, 6955.791326260208, 7294.489385742987, 8549.658013679453, 8745.169208925157, 8754.168354006375, 8271.296939760128, 8481.406301558893, 8630.741077767678, 8651.69719059833, 8677.609507572159, 2446.2049833803994, 2453.116877923946, 2460.052879641342, 2470.110760126353, 2470.197185914576, 2425.7695518925675, 2487.315988103268, 2494.3764058985253, 2497.7876880465183, 2502.724700282967, 2139.764625891151, 2391.7369089400154, 2438.207582535674, 2456.2140115163156, 2471.122751423246, 2421.794147497465, 2431.8905649245826, 2453.0678342229926, 2459.1270099114913, 2463.408781892351, 2445.4864478459544, 2455.312512496504, 2455.656208130863, 2464.8397854139425, 2474.2824580871525, 8598.0, 8622.180892815235, 8633.438826476258, 8647.481151130889, 8691.34814888886, 5305.7877684892555, 5410.08028635129, 5421.1326187810155, 5463.836084917459, 5468.371137319219, 3656.2504686539196, 3691.206390626028, 3738.2710371477574, 3744.6817020553285, 3751.0622344413896, 909.1817364372052, 1046.4192373232852, 1065.4193464561833, 1079.0, 1102.2339474065527, 11508.597199098074, 11596.59411920587, 11602.12984026193, 11602.535898564047, 11637.254411838207, 4129.97935010323, 4826.32431459596, 4850.0, 4873.585745211626, 4926.472793434047, 9932.751681208028, 9964.950175249078, 10056.597736090474, 10060.346077504939, 10065.4464004479, 5265.4997775211295, 5290.788368465295, 5299.3905700844125, 5347.41178470368, 5364.329458817648, 4738.715677059387, 4746.95253047472, 4749.26386410107, 4751.762411879392, 4768.070226305837, 3889.0666240102173, 3945.408188771707, 3985.561588225293, 3988.4615576897327, 3992.001999500125, 996.4967691315984, 1334.6129622409392, 1335.9866401335862, 1340.0753480098701, 1340.9530666426629, 1130.4460814200982, 1132.8923752243525, 1157.4328578996399, 1284.9614511564669, 1366.064245991499, 7877.251661092187, 7886.44794864354, 7938.64276107565, 8006.119326874039, 8017.639206235734, 1172.5661505243115, 1173.436750359832, 1189.6312143235625, 1190.8451901252781, 1214.5627574073326, 6058.091286307089, 6097.939020609826, 6153.107799369066, 6232.345603711636, 6236.501079913687, 10244.743881403025, 10498.842517362245, 10501.842472362921, 10580.52387642558, 10618.362898225976, 10026.298159128792, 10410.843837342445, 10454.268201225994, 10465.529051192714, 10467.84298235527, 8092.71675491355, 8155.71454999084, 8174.6730130795295, 8194.262516373556, 8257.38069644779, 6052.969735151472, 7536.6057279403485, 7794.727184548514, 7924.4056695746895, 8022.478538894953, 8348.33213342919, 9048.457092574467, 9110.0, 9337.346385752931, 9511.762205944906, 9476.336656433983, 9700.6119755209, 9718.465484398426, 9775.804483910424, 9804.754881127743, 7351.926480735338, 7422.1835598085245, 7446.70213191488, 7467.77596672079, 7477.327040566375, 7964.123946366011, 8185.754427367188, 8195.385346098947, 8204.58977051157, 8210.261076503144, 6956.652167391509, 7715.537067775951, 7781.2218778124, 7884.2115788423, 7892.329152021913, 7396.96301518489, 7433.591152486665, 7466.813329666802, 7541.396688265036, 7567.545947243183, 7553.471257011956, 7611.809704757426, 7618.961905190438, 7644.847103058019, 7650.387968962581, 6770.086038384755, 6928.0, 6968.3728464438445, 6977.232504424611, 6978.232394436709, 7027.859442811217, 7091.60996145217, 7097.503174777922, 7110.3600675939415, 7144.892826607709], "unit": "score", "name": "mootools.html", "value": 4898.887948813751}, {"lowerIsBetter": false, "replicates": [325.51498267581303, 329.23452971840464, 330.3986744125692, 335.3879170513813, 336.6179386396439, 6709.261981182066, 6875.415589674883, 6903.067141200466, 6916.17005959285, 7011.754588589407, 6837.213720422153, 6851.554648947827, 6873.243943166261, 6875.243723190445, 6894.172699276094, 4421.823127074913, 4744.64415168862, 4993.251012348141, 5026.396832380119, 5030.943501864613, 4834.564889159975, 5035.0, 5051.2928190053235, 5058.11482990477, 5063.898722025558, 3978.6220309070736, 4890.413150421954, 4897.0, 4904.0437114762635, 4923.77842997066, 4909.189983652693, 4917.434495033063, 4920.532549407819, 4933.777979990909, 4953.529414705633, 4153.397757325171, 4173.895652608679, 4184.28867092595, 4198.265303571863, 4202.2646036943715, 6323.683815809191, 6549.869002619921, 6578.8355291118105, 6605.867882642368, 6638.336166383323, 5852.151438041503, 5867.941320586825, 5870.530357571384, 5884.146798714162, 5884.176215329857, 7407.592582407965, 7418.035655364822, 7419.443541734391, 7421.8144546386775, 7433.516821406592, 4481.731096134243, 4617.976910115428, 4681.0871879983415, 4693.014466961942, 4709.952900470986, 7479.775606731752, 7698.653560589731, 7720.961395192988, 7724.995750552393, 7768.689252429896, 4723.834665786681, 4741.786619602126, 4747.667663263538, 4753.835310348986, 4754.096721622881, 4758.468690022157, 4813.061453016699, 4831.323614693875, 4840.322354870321, 4856.660033797601, 5815.5929084963645, 5820.3888591698105, 5824.854378640568, 5856.267966504187, 5859.472647461749, 7333.413326933833, 7337.0828646419195, 7338.4496162788, 7343.412526997828, 7377.151627562899, 1426.3153686230667, 1480.7334679757746, 1675.6229848284038, 1687.8227786082284, 1703.9829601703825, 944.1738478831023, 1047.2773786087575, 1076.2842709598185, 1081.810683130449, 1088.4122573810055, 6758.966205169041, 6788.456923446112, 6826.112605361272, 6826.897596535957, 6832.6925288362145, 7550.697972081165, 7607.809804754926, 7621.580813055335, 7674.079110506664, 7695.115061767857, 6553.836154096186, 6741.865162696817, 6752.662366881735, 6792.898106528406, 6844.691988860513, 3251.219707270238, 3928.901777455529, 4064.0, 4073.246449406869, 4076.3681629347498, 7899.0126234220725, 7914.722984695508, 7920.247576480225, 7949.205079492004, 7969.481983671043, 6159.5072394209255, 6453.354664533509, 7848.254415830487, 7905.209479052164, 8001.239882211181, 5816.854578635568, 6951.374376306056, 7941.6029198541, 8175.427720059539, 8217.506949583045, 7393.8521229574035, 7622.123455802432, 7803.7268695595385, 7810.921890781019, 7862.095858976062, 7646.15892251863, 7686.154523002577, 7702.114256860533, 7710.614469276402, 7729.458937874295, 6612.437942774918, 7891.605419729105, 8137.959310203411, 8154.2253485918745, 8171.305439037511, 3926.2736393767245, 3986.621270979252, 4850.53919877611, 4871.123197824284, 4873.512648735098, 7748.883766743391, 7768.417368697257, 7779.883301750591, 7781.883271751041, 7802.21977802238, 7336.156342020522, 7512.361449276873, 7513.549187048576, 7515.173330933702, 7533.7363192288, 7491.625418729151, 7527.360174385239, 7531.133919599316, 7533.058367704037, 7543.245675432413, 7301.963490182515, 7331.633418329169, 7352.77941661751, 7360.631968401452, 7362.153352364547, 7288.234735352712, 7510.812229694301, 7517.699292028257, 7522.322990930843, 7534.962325188339, 7308.926910730825, 7335.963320183365, 7339.8347487490255, 7347.55914645123, 7355.117385913724], "unit": "score", "name": "prototype.html", "value": 5314.798701440047}, {"lowerIsBetter": false, "replicates": [598.7425407074958, 657.8059472455625, 692.1797669761336, 732.3079689693238, 734.3574372424129, 14942.701145977087, 15016.849831501682, 15113.924430377847, 17126.0, 17293.0488823115, 15783.28975196116, 15798.526044218692, 15854.682906341866, 15862.762058569131, 15870.206489675527, 684.5174152222696, 842.6460886427715, 853.3642436384871, 862.7886167888876, 865.2429124516049, 857.3998201259112, 857.7769779857219, 863.7581477186367, 868.9608967596441, 869.0483920107504, 879.6833140069601, 887.1572006593761, 904.6291020681522, 906.0441234497603, 907.5734404829719, 887.6981826179131, 890.6615486115268, 895.632790555869, 899.7705585075829, 901.0268909577674, 659.7361055577759, 663.8904580744171, 664.4651055899999, 666.0575285970347, 667.5227212543055, 15225.467108651255, 15227.390904363812, 15290.770638440432, 15293.464728734552, 15301.540953771404, 12562.623121306375, 12609.180403273758, 12678.619641410773, 12840.743185136338, 13163.341832908316, 9208.585613647401, 9920.107190352903, 9998.55006524701, 11149.99650031493, 11950.940245298805, 3401.268727223659, 3458.291050334675, 3475.7566970312087, 3516.6659167379057, 3521.366154092288, 56434.0, 59085.0, 63043.68478157626, 63072.05391919125, 63223.68388158076, 9215.0, 9301.441913485212, 9364.672236471826, 9382.624695012122, 9560.187384072431, 19919.203231870564, 19959.700604490943, 20070.799292006894, 20135.899320503595, 20180.798192018185, 7426.4430167736555, 7449.366803821736, 7609.733659322008, 7768.572728499874, 7977.162397948132, 4034.9394759078637, 4085.632293093636, 4116.217918595457, 4171.687123465692, 4325.675574331937, 3517.43721004638, 3560.9851192410038, 3562.7684200526883, 3566.964330356715, 3574.946375804365, 661.0843981086134, 663.8041777675501, 668.2949488289804, 674.9628770417576, 683.5044592670374, 568.0286709726414, 570.1960236067125, 589.4871461828237, 597.9760809567568, 624.2384291164772, 7918.683252669829, 8739.91260087391, 8990.730278091667, 9074.546272686339, 9132.22376098039, 508.5905845794097, 510.9693418394908, 535.7883635963773, 559.381883019266, 565.1974196640698, 3762.698984081267, 3938.6455219030427, 3979.303621866162, 4064.1058967027207, 4081.97959010209, 94240.58639120418, 94949.0, 96474.0, 96595.0, 96656.0, 79978.20021799824, 81055.0, 81075.0, 81324.59337703274, 81605.77591336134, 68222.0, 69135.0, 70499.99999999898, 70867.0, 70889.0, 8015.879761803461, 8307.293880020266, 8351.749447516584, 8709.564521773758, 8767.298616110824, 38863.0, 45874.77062614666, 51974.740126299126, 52074.147394943706, 52251.216231755796, 56803.295901123034, 63001.68499157475, 71085.64457177887, 71368.64315678389, 71414.0, 8769.517676527852, 11251.437428128396, 11360.318380896842, 11673.708157296136, 12399.504019839465, 11700.648980530597, 12027.518899243933, 12231.388430578614, 12311.261324320569, 12717.745645087232, 9256.028117047883, 10297.867234604038, 11054.723631909266, 11333.603323883624, 11427.0, 9832.410055396698, 11246.550137994389, 11314.660560183207, 11404.771904562029, 11500.482478288664, 10831.51258193399, 11554.537818487166, 11735.823962640738, 11738.70653233676, 11767.823217678164, 9373.906260937576, 9429.905700943178, 9592.952035240058, 9903.50482475859, 10101.191904647463, 7622.656980435783, 8006.439549231498, 8644.610992505226, 9434.858477122985, 10103.191744660557], "unit": "score", "name": "yui.html", "value": 6816.14817486115}], "name": "dromaeo_css", "value": 7484.130367382722}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 104.0, 114.0, 113.0, 117.0, 126.0, 113.0, 118.0, 166.0, 115.0], "unit": "score", "name": "ai-astar", "value": 120.0}, {"lowerIsBetter": true, "replicates": [150.0, 123.0, 143.0, 149.0, 124.0, 140.0, 140.0, 121.0, 154.0, 122.0], "unit": "score", "name": "audio-beat-detection", "value": 136.6}, {"lowerIsBetter": true, "replicates": [200.0, 203.0, 194.0, 212.0, 200.0, 209.0, 210.0, 197.0, 235.0, 227.0], "unit": "score", "name": "audio-dft", "value": 208.7}, {"lowerIsBetter": true, "replicates": [77.0, 73.0, 73.0, 109.0, 56.0, 57.0, 59.0, 60.0, 116.0, 78.0], "unit": "score", "name": "audio-fft", "value": 75.8}, {"lowerIsBetter": true, "replicates": [81.0, 86.0, 122.0, 79.0, 82.0, 86.0, 127.0, 83.0, 82.0, 114.0], "unit": "score", "name": "audio-oscillator", "value": 94.2}, {"lowerIsBetter": true, "replicates": [86.0, 106.0, 86.0, 87.0, 128.0, 139.0, 86.0, 101.0, 86.0, 94.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 99.9}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 106.0, 118.0, 120.0, 123.0, 116.0, 144.0, 151.0, 151.0], "unit": "score", "name": "imaging-darkroom", "value": 122.0}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 91.0, 93.0, 93.0, 108.0, 145.0, 105.0, 87.0, 150.0], "unit": "score", "name": "imaging-desaturate", "value": 105.5}, {"lowerIsBetter": true, "replicates": [52.0, 83.0, 83.0, 46.0, 48.0, 59.0, 85.0, 90.0, 84.0, 79.0], "unit": "score", "name": "json-parse-financial", "value": 70.9}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 70.0, 71.0, 73.0, 72.0, 68.0, 66.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 114.0, 101.0, 86.0, 90.0, 88.0, 89.0, 84.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 91.0}, {"lowerIsBetter": true, "replicates": [117.0, 138.0, 142.0, 130.0, 140.0, 145.0, 114.0, 139.0, 137.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 133.5}, {"lowerIsBetter": true, "replicates": [152.0, 141.0, 129.0, 177.0, 177.0, 141.0, 138.0, 140.0, 151.0, 181.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.7}, {"lowerIsBetter": true, "replicates": [72.0, 99.0, 66.0, 60.0, 60.0, 59.0, 59.0, 98.0, 61.0, 89.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 72.3}], "name": "kraken", "value": 1551.5000000000002}]} 09:22:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,61]]", "result": "1551.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,61]]", "result": "7484.13"}}} 09:22:41 INFO - Return code: 0 09:22:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:41 INFO - # TBPL SUCCESS # 09:22:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:41 INFO - 2016-02-11 09:22:41.177 screenresolution[1691:10613] starting screenresolution argv=screenresolution get 09:22:41 INFO - 2016-02-11 09:22:41.180 screenresolution[1691:10613] Display 0: 1600x1200x32@60 09:22:41 INFO - 2016-02-11 09:22:41.186 screenresolution[1692:10616] starting screenresolution argv=screenresolution list 09:22:41 INFO - Available Modes on Display 0 09:22:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:41 INFO - Intel Iris: 09:22:41 INFO - Chipset Model: Intel Iris 09:22:41 INFO - Type: GPU 09:22:41 INFO - Bus: Built-In 09:22:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:41 INFO - Vendor: Intel (0x8086) 09:22:41 INFO - Device ID: 0x0a2e 09:22:41 INFO - Revision ID: 0x0009 09:22:41 INFO - Displays: 09:22:41 INFO - Display: 09:22:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:41 INFO - Display Serial Number: 1600x1200 60 09:22:41 INFO - Main Display: Yes 09:22:41 INFO - Mirror: Off 09:22:41 INFO - Online: Yes 09:22:41 INFO - Rotation: Supported 09:22:41 INFO - Return code: 0 09:22:41 INFO - Running post-action listener: _resource_record_post_action 09:22:41 INFO - Running post-run listener: _resource_record_post_run 09:22:42 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 39192064; Write bytes: 712369152; Read time: 426; Write time: 2062 09:22:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404617728; Write bytes: 407296000; Read time: 30289; Write time: 779 09:22:42 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 37377536; Write bytes: 304225280; Read time: 405; Write time: 1282 09:22:42 INFO - Running post-run listener: _upload_blobber_files 09:22:42 INFO - Blob upload gear active. 09:22:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:42 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:22:42 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:22:42 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:22:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:44 INFO - (blobuploader) - INFO - Done attempting. 09:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:45 INFO - (blobuploader) - INFO - Done attempting. 09:22:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:45 INFO - Return code: 0 09:22:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/434e23360e2acc75dfd9944ece9f203fb4bb1dfdf5b7849e0359f2fb6c14799a46a0f9a942fb6375a05b411085c3a216e99c433fb62bd8f809854fb52a86866f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/796037a1816a43334f7c950dcc2f6763076390533a04db82b5d722a1394b27019870f02b49e31cfb1df93840f6741299a45388a6547369a57e1b9dbd44757f92"} 09:22:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:45 INFO - Contents: 09:22:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/434e23360e2acc75dfd9944ece9f203fb4bb1dfdf5b7849e0359f2fb6c14799a46a0f9a942fb6375a05b411085c3a216e99c433fb62bd8f809854fb52a86866f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/796037a1816a43334f7c950dcc2f6763076390533a04db82b5d722a1394b27019870f02b49e31cfb1df93840f6741299a45388a6547369a57e1b9dbd44757f92"} 09:22:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:45 INFO - Copying logs to upload dir... 09:22:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.425934 ========= master_lag: 3.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-11 09:22:48.528165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-11 09:22:48.532815) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/434e23360e2acc75dfd9944ece9f203fb4bb1dfdf5b7849e0359f2fb6c14799a46a0f9a942fb6375a05b411085c3a216e99c433fb62bd8f809854fb52a86866f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/796037a1816a43334f7c950dcc2f6763076390533a04db82b5d722a1394b27019870f02b49e31cfb1df93840f6741299a45388a6547369a57e1b9dbd44757f92"} build_url:https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011594 build_url: 'https://queue.taskcluster.net/v1/task/cqBrvq7gQHmpdd8kwM0RpA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/434e23360e2acc75dfd9944ece9f203fb4bb1dfdf5b7849e0359f2fb6c14799a46a0f9a942fb6375a05b411085c3a216e99c433fb62bd8f809854fb52a86866f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/796037a1816a43334f7c950dcc2f6763076390533a04db82b5d722a1394b27019870f02b49e31cfb1df93840f6741299a45388a6547369a57e1b9dbd44757f92"}' ========= master_lag: 1.31 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-11 09:22:49.849996) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:22:49.850286) ========= 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.p4o4cFHhlT/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.bUcdsSRsIC/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005161 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:22:49.939343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 09:22:49.939665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 09:22:49.946004) ========= ========= Total master_lag: 6.32 =========