builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0043 starttime: 1455023581.65 results: success (0) buildid: 20160209030347 builduid: 0bb195ecac6b4153b86503f213989972 revision: d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.648776) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.649179) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.649460) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.006073 basedir: '/builds/slave/test' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.709405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.709671) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.825892) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.826353) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.005427 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.896492) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.896754) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.897107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:01.897377) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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-09 05:13:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-02-09 05:13:02 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.608651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:02.529238) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:02.529538) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.020217 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:02.584890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:02.585184) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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-09 05:13:02,647 truncating revision to first 12 chars 2016-02-09 05:13:02,647 Setting DEBUG logging. 2016-02-09 05:13:02,647 attempt 1/10 2016-02-09 05:13:02,647 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d1a54ae63da7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-09 05:13:03,343 unpacking tar archive at: mozilla-central-d1a54ae63da7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944894 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:03.543896) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:03.544226) ========= echo d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a program finished with exit code 0 elapsedTime=0.005253 script_repo_revision: 'd1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:03.594481) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:03.594789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-09 05:13:03.607305) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-02-09 05:13:03.607730) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:13:03 INFO - MultiFileLogger online at 20160209 05:13:03 in /builds/slave/test 05:13:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:13:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:13:03 INFO - {'append_to_log': False, 05:13:03 INFO - 'base_work_dir': '/builds/slave/test', 05:13:03 INFO - 'blob_upload_branch': 'Firefox', 05:13:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:13:03 INFO - 'branch': 'Firefox', 05:13:03 INFO - 'buildbot_json_path': 'buildprops.json', 05:13:03 INFO - 'config_files': ('talos/mac_config.py',), 05:13:03 INFO - 'default_actions': ('clobber', 05:13:03 INFO - 'read-buildbot-config', 05:13:03 INFO - 'download-and-extract', 05:13:03 INFO - 'populate-webroot', 05:13:03 INFO - 'create-virtualenv', 05:13:03 INFO - 'install', 05:13:03 INFO - 'run-tests'), 05:13:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:13:03 INFO - 'download_minidump_stackwalk': True, 05:13:03 INFO - 'download_symbols': 'ondemand', 05:13:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:13:03 INFO - 'tooltool.py': '/tools/tooltool.py', 05:13:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:13:03 INFO - '/tools/misc-python/virtualenv.py')}, 05:13:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:13:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:13:03 INFO - 'installer_path': 'installer.exe', 05:13:03 INFO - 'log_level': 'info', 05:13:03 INFO - 'log_name': 'talos', 05:13:03 INFO - 'log_to_console': True, 05:13:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:13:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:13:03 INFO - 'opt_config_files': (), 05:13:03 INFO - 'pip_index': False, 05:13:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:03 INFO - 'cmd': ('bash', 05:13:03 INFO - '-c', 05:13:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:03 INFO - 'enabled': True, 05:13:03 INFO - 'halt_on_failure': False, 05:13:03 INFO - 'name': 'check_screen_resolution'},), 05:13:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:03 INFO - 'cmd': ('bash', 05:13:03 INFO - '-c', 05:13:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:03 INFO - 'enabled': True, 05:13:03 INFO - 'halt_on_failure': False, 05:13:03 INFO - 'name': 'check_screen_resolution'},), 05:13:03 INFO - 'run_cmd_checks_enabled': True, 05:13:03 INFO - 'sps_profile': False, 05:13:03 INFO - 'sps_profile_interval': 0, 05:13:03 INFO - 'suite': 'dromaeojs', 05:13:03 INFO - 'system_bits': '32', 05:13:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:13:03 INFO - 'title': 't-yosemite-r7-0043', 05:13:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:13:03 INFO - 'use_talos_json': True, 05:13:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:13:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:13:03 INFO - 'work_dir': 'build'} 05:13:03 INFO - ##### 05:13:03 INFO - ##### Running clobber step. 05:13:03 INFO - ##### 05:13:03 INFO - Running pre-action listener: _resource_record_pre_action 05:13:03 INFO - Running main action method: clobber 05:13:03 INFO - rmtree: /builds/slave/test/build 05:13:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:13:04 INFO - Running post-action listener: _resource_record_post_action 05:13:04 INFO - ##### 05:13:04 INFO - ##### Running read-buildbot-config step. 05:13:04 INFO - ##### 05:13:04 INFO - Running pre-action listener: _resource_record_pre_action 05:13:04 INFO - Running main action method: read_buildbot_config 05:13:04 INFO - Using buildbot properties: 05:13:04 INFO - { 05:13:04 INFO - "project": "", 05:13:04 INFO - "product": "firefox", 05:13:04 INFO - "script_repo_revision": "production", 05:13:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:13:04 INFO - "repository": "", 05:13:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:13:04 INFO - "buildid": "20160209030347", 05:13:04 INFO - "stage_platform": "macosx64", 05:13:04 INFO - "basedir": "/builds/slave/test", 05:13:04 INFO - "nightly_build": "True", 05:13:04 INFO - "buildnumber": 111, 05:13:04 INFO - "slavename": "t-yosemite-r7-0043", 05:13:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:13:04 INFO - "platform": "yosemite_r7", 05:13:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:13:04 INFO - "branch": "mozilla-central", 05:13:04 INFO - "repo_path": "mozilla-central", 05:13:04 INFO - "revision": "d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a", 05:13:04 INFO - "pgo_build": "False", 05:13:04 INFO - "builduid": "0bb195ecac6b4153b86503f213989972", 05:13:04 INFO - "slavebuilddir": "test" 05:13:04 INFO - } 05:13:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 05:13:04 INFO - Running post-action listener: _resource_record_post_action 05:13:04 INFO - ##### 05:13:04 INFO - ##### Running download-and-extract step. 05:13:04 INFO - ##### 05:13:04 INFO - Running pre-action listener: _resource_record_pre_action 05:13:04 INFO - Running main action method: download_and_extract 05:13:04 INFO - mkdir: /builds/slave/test/build/tests 05:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:04 INFO - https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:13:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/test_packages.json 05:13:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/test_packages.json 05:13:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:13:05 INFO - Downloaded 1277 bytes. 05:13:05 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:13:05 INFO - Using the following test package requirements: 05:13:05 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:13:05 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:13:05 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:13:05 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'jsshell-mac.zip'], 05:13:05 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:13:05 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:13:05 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:13:05 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:13:05 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:13:05 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:13:05 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:13:05 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:13:05 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:13: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')]} 05:13:05 INFO - https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:08 INFO - Downloaded 17754864 bytes. 05:13:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:11 INFO - Return code: 0 05:13:11 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:11 INFO - https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:13:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:14 INFO - Downloaded 17754864 bytes. 05:13:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:17 INFO - Return code: 0 05:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:17 INFO - https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:13:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:13:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:13:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:13:19 INFO - Downloaded 10706547 bytes. 05:13:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:13:19 INFO - Return code: 0 05:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:19 INFO - https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:13:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:13:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:13:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:13:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 05:13:23 INFO - Downloaded 111964397 bytes. 05:13:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:13:23 INFO - mkdir: /builds/slave/test/properties 05:13:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:23 INFO - Contents: 05:13:23 INFO - build_url:https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:13:24 INFO - Running post-action listener: _resource_record_post_action 05:13:24 INFO - Running post-action listener: set_extra_try_arguments 05:13:24 INFO - ##### 05:13:24 INFO - ##### Running populate-webroot step. 05:13:24 INFO - ##### 05:13:24 INFO - Running pre-action listener: _resource_record_pre_action 05:13:24 INFO - Running main action method: populate_webroot 05:13:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:24 INFO - '--tppagecycles', 05:13:24 INFO - '7'], 05:13:24 INFO - 'tests': ['tsvgm']}}, 05:13:24 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:13:24 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:24 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:13:24 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'tests': ['damp', 'tps']}, 05:13:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['damp', 'tps']}, 05:13:24 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:13:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:24 INFO - 'other': {'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'other_l64': {'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'other_nol64': {'tests': ['a11yr', 05:13:24 INFO - 'ts_paint', 05:13:24 INFO - 'tpaint', 05:13:24 INFO - 'sessionrestore', 05:13:24 INFO - 'sessionrestore_no_auto_restore']}, 05:13:24 INFO - 'svgr': {'tests': ['tsvgx', 05:13:24 INFO - 'tsvgr_opacity', 05:13:24 INFO - 'tart', 05:13:24 INFO - 'tscrollx', 05:13:24 INFO - 'cart']}, 05:13:24 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['tsvgx', 05:13:24 INFO - 'tsvgr_opacity', 05:13:24 INFO - 'tart', 05:13:24 INFO - 'tscrollx', 05:13:24 INFO - 'cart']}, 05:13:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'tests': ['tp5o']}, 05:13:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'talos_options': ['--e10s'], 05:13:24 INFO - 'tests': ['tp5o']}, 05:13:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'talos_options': ['--xperf_path', 05:13:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:24 INFO - 'tests': ['tp5n']}, 05:13:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:24 INFO - 'talos_options': ['--e10s', 05:13:24 INFO - '--xperf_path', 05:13:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:24 INFO - 'tests': ['tp5n']}}, 05:13:24 INFO - 'talos.zip': {'path': '', 05:13:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:24 INFO - Running post-action listener: _resource_record_post_action 05:13:24 INFO - ##### 05:13:24 INFO - ##### Running create-virtualenv step. 05:13:24 INFO - ##### 05:13:24 INFO - Running pre-action listener: _resource_record_pre_action 05:13:24 INFO - Running main action method: create_virtualenv 05:13:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:13:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:24 INFO - Using real prefix '/tools/python27' 05:13:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:28 INFO - Installing pip.................done. 05:13:28 INFO - Return code: 0 05:13:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13: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', 'pip>=1.5'] in /builds/slave/test/build 05:13: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 pip>=1.5 05:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:28 INFO - 'HOME': '/Users/cltbld', 05:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:28 INFO - 'LOGNAME': 'cltbld', 05:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:28 INFO - 'MOZ_NO_REMOTE': '1', 05:13:28 INFO - 'NO_EM_RESTART': '1', 05:13:28 INFO - 'PAGER': '/bin/cat', 05:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:28 INFO - 'PWD': '/builds/slave/test', 05:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:28 INFO - 'SHELL': '/bin/bash', 05:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:28 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:28 INFO - 'USER': 'cltbld', 05:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:28 INFO - 'XPC_FLAGS': '0x0', 05:13:28 INFO - 'XPC_SERVICE_NAME': '0', 05:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:13:28 INFO - Cleaning up... 05:13:28 INFO - Return code: 0 05:13:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', '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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13: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', 'psutil>=0.7.1'] in /builds/slave/test/build 05:13: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 psutil>=0.7.1 05:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:28 INFO - 'HOME': '/Users/cltbld', 05:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:28 INFO - 'LOGNAME': 'cltbld', 05:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:28 INFO - 'MOZ_NO_REMOTE': '1', 05:13:28 INFO - 'NO_EM_RESTART': '1', 05:13:28 INFO - 'PAGER': '/bin/cat', 05:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:28 INFO - 'PWD': '/builds/slave/test', 05:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:28 INFO - 'SHELL': '/bin/bash', 05:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:28 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:28 INFO - 'USER': 'cltbld', 05:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:28 INFO - 'XPC_FLAGS': '0x0', 05:13:28 INFO - 'XPC_SERVICE_NAME': '0', 05:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:28 INFO - Downloading/unpacking psutil>=0.7.1 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:31 INFO - Installing collected packages: psutil 05:13:31 INFO - Running setup.py install for psutil 05:13:31 INFO - building 'psutil._psutil_osx' extension 05:13:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:13:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:13:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:13:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:13:32 INFO - building 'psutil._psutil_posix' extension 05:13:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:13:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:13:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:13:32 INFO - ^ 05:13:32 INFO - 1 warning generated. 05:13:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:13:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:32 INFO - Successfully installed psutil 05:13:32 INFO - Cleaning up... 05:13:32 INFO - Return code: 0 05:13:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:32 INFO - 'HOME': '/Users/cltbld', 05:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:32 INFO - 'LOGNAME': 'cltbld', 05:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:32 INFO - 'MOZ_NO_REMOTE': '1', 05:13:32 INFO - 'NO_EM_RESTART': '1', 05:13:32 INFO - 'PAGER': '/bin/cat', 05:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:32 INFO - 'PWD': '/builds/slave/test', 05:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:32 INFO - 'SHELL': '/bin/bash', 05:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:32 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:32 INFO - 'USER': 'cltbld', 05:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:32 INFO - 'XPC_FLAGS': '0x0', 05:13:32 INFO - 'XPC_SERVICE_NAME': '0', 05:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:34 INFO - Installing collected packages: mozsystemmonitor 05:13:34 INFO - Running setup.py install for mozsystemmonitor 05:13:35 INFO - Successfully installed mozsystemmonitor 05:13:35 INFO - Cleaning up... 05:13:35 INFO - Return code: 0 05:13:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:13:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:35 INFO - 'HOME': '/Users/cltbld', 05:13:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:35 INFO - 'LOGNAME': 'cltbld', 05:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:35 INFO - 'MOZ_NO_REMOTE': '1', 05:13:35 INFO - 'NO_EM_RESTART': '1', 05:13:35 INFO - 'PAGER': '/bin/cat', 05:13:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:35 INFO - 'PWD': '/builds/slave/test', 05:13:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:35 INFO - 'SHELL': '/bin/bash', 05:13:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:35 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:35 INFO - 'USER': 'cltbld', 05:13:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:35 INFO - 'XPC_FLAGS': '0x0', 05:13:35 INFO - 'XPC_SERVICE_NAME': '0', 05:13:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:38 INFO - Downloading docopt-0.6.1.tar.gz 05:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:38 INFO - Running setup.py install for blobuploader 05:13:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:39 INFO - Running setup.py install for requests 05:13:39 INFO - Running setup.py install for docopt 05:13:39 INFO - Successfully installed blobuploader requests docopt 05:13:39 INFO - Cleaning up... 05:13:39 INFO - Return code: 0 05:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:39 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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:39 INFO - 'HOME': '/Users/cltbld', 05:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:39 INFO - 'LOGNAME': 'cltbld', 05:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:39 INFO - 'MOZ_NO_REMOTE': '1', 05:13:39 INFO - 'NO_EM_RESTART': '1', 05:13:39 INFO - 'PAGER': '/bin/cat', 05:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:39 INFO - 'PWD': '/builds/slave/test', 05:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:39 INFO - 'SHELL': '/bin/bash', 05:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:39 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:39 INFO - 'USER': 'cltbld', 05:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:39 INFO - 'XPC_FLAGS': '0x0', 05:13:39 INFO - 'XPC_SERVICE_NAME': '0', 05:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-LorBGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-gBTXXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-hO76Dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-AKLPet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-hAWpSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-3L_kdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-oqO4XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-qzHCCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:40 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-917kQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-6E0zO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-z0Gk4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Q0Gejw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-OQezCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ffdYSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-2zBSb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-DAjW5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:41 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-7A7X7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:41 INFO - Running setup.py install for manifestparser 05:13:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:42 INFO - Running setup.py install for mozcrash 05:13:42 INFO - Running setup.py install for mozdebug 05:13:42 INFO - Running setup.py install for mozdevice 05:13:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:13:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:42 INFO - Running setup.py install for mozfile 05:13:42 INFO - Running setup.py install for mozhttpd 05:13:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:42 INFO - Running setup.py install for mozinfo 05:13:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:42 INFO - Running setup.py install for mozInstall 05:13:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Running setup.py install for mozleak 05:13:43 INFO - Running setup.py install for mozlog 05:13:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Running setup.py install for moznetwork 05:13:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Running setup.py install for mozprocess 05:13:43 INFO - Running setup.py install for mozprofile 05:13:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Running setup.py install for mozrunner 05:13:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:43 INFO - Running setup.py install for mozscreenshot 05:13:44 INFO - Running setup.py install for moztest 05:13:44 INFO - Running setup.py install for mozversion 05:13:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:44 INFO - Cleaning up... 05:13:44 INFO - Return code: 0 05:13:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:44 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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:44 INFO - 'HOME': '/Users/cltbld', 05:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:44 INFO - 'LOGNAME': 'cltbld', 05:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:44 INFO - 'MOZ_NO_REMOTE': '1', 05:13:44 INFO - 'NO_EM_RESTART': '1', 05:13:44 INFO - 'PAGER': '/bin/cat', 05:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:44 INFO - 'PWD': '/builds/slave/test', 05:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:44 INFO - 'SHELL': '/bin/bash', 05:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:44 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:44 INFO - 'USER': 'cltbld', 05:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:44 INFO - 'XPC_FLAGS': '0x0', 05:13:44 INFO - 'XPC_SERVICE_NAME': '0', 05:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:44 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-MZ6CYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:44 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-0DYSdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:44 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-qRoLpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-HPwJfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-meynZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-0CCbZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-gRse6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-9M14Sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-sEx215-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-2RYvvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Fq1g_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:45 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-eWMdEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:46 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-XZMvb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:46 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-HxC6wL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:46 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ggGoK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:46 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ckYndA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:46 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-3uZYSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:13:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:48 INFO - Downloading blessings-1.6.tar.gz 05:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:48 INFO - Installing collected packages: blessings 05:13:48 INFO - Running setup.py install for blessings 05:13:49 INFO - Successfully installed blessings 05:13:49 INFO - Cleaning up... 05:13:49 INFO - Return code: 0 05:13:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:49 INFO - Reading from file tmpfile_stdout 05:13:49 INFO - Current package versions: 05:13:49 INFO - blessings == 1.6 05:13:49 INFO - blobuploader == 1.2.4 05:13:49 INFO - docopt == 0.6.1 05:13:49 INFO - manifestparser == 1.1 05:13:49 INFO - mozInstall == 1.12 05:13:49 INFO - mozcrash == 0.16 05:13:49 INFO - mozdebug == 0.1 05:13:49 INFO - mozdevice == 0.48 05:13:49 INFO - mozfile == 1.2 05:13:49 INFO - mozhttpd == 0.7 05:13:49 INFO - mozinfo == 0.9 05:13:49 INFO - mozleak == 0.1 05:13:49 INFO - mozlog == 3.1 05:13:49 INFO - moznetwork == 0.27 05:13:49 INFO - mozprocess == 0.22 05:13:49 INFO - mozprofile == 0.28 05:13:49 INFO - mozrunner == 6.11 05:13:49 INFO - mozscreenshot == 0.1 05:13:49 INFO - mozsystemmonitor == 0.0 05:13:49 INFO - moztest == 0.7 05:13:49 INFO - mozversion == 1.4 05:13:49 INFO - psutil == 3.1.1 05:13:49 INFO - requests == 1.2.3 05:13:49 INFO - wsgiref == 0.1.2 05:13:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:49 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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:49 INFO - 'HOME': '/Users/cltbld', 05:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:49 INFO - 'LOGNAME': 'cltbld', 05:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:49 INFO - 'MOZ_NO_REMOTE': '1', 05:13:49 INFO - 'NO_EM_RESTART': '1', 05:13:49 INFO - 'PAGER': '/bin/cat', 05:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:49 INFO - 'PWD': '/builds/slave/test', 05:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:49 INFO - 'SHELL': '/bin/bash', 05:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:49 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:49 INFO - 'USER': 'cltbld', 05:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:49 INFO - 'XPC_FLAGS': '0x0', 05:13:49 INFO - 'XPC_SERVICE_NAME': '0', 05:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:13:49 INFO - Cleaning up... 05:13:49 INFO - Return code: 0 05:13:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:49 INFO - 'HOME': '/Users/cltbld', 05:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:49 INFO - 'LOGNAME': 'cltbld', 05:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:49 INFO - 'MOZ_NO_REMOTE': '1', 05:13:49 INFO - 'NO_EM_RESTART': '1', 05:13:49 INFO - 'PAGER': '/bin/cat', 05:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:49 INFO - 'PWD': '/builds/slave/test', 05:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:49 INFO - 'SHELL': '/bin/bash', 05:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:49 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:49 INFO - 'USER': 'cltbld', 05:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:49 INFO - 'XPC_FLAGS': '0x0', 05:13:49 INFO - 'XPC_SERVICE_NAME': '0', 05:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:49 INFO - Downloading/unpacking jsonschema 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:52 INFO - Downloading/unpacking functools32 (from jsonschema) 05:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:52 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:52 INFO - warning: no files found matching '*.txt' 05:13:52 INFO - no previously-included directories found matching 'build' 05:13:52 INFO - no previously-included directories found matching 'dist' 05:13:52 INFO - no previously-included directories found matching '.git*' 05:13:52 INFO - Installing collected packages: jsonschema, functools32 05:13:52 INFO - Running setup.py install for functools32 05:13:52 INFO - warning: no files found matching '*.txt' 05:13:52 INFO - no previously-included directories found matching 'build' 05:13:52 INFO - no previously-included directories found matching 'dist' 05:13:52 INFO - no previously-included directories found matching '.git*' 05:13:52 INFO - Successfully installed jsonschema functools32 05:13:52 INFO - Cleaning up... 05:13:53 INFO - Return code: 0 05:13:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:53 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 0x1022a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ad4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec50c8fa30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, '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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:53 INFO - 'HOME': '/Users/cltbld', 05:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:53 INFO - 'LOGNAME': 'cltbld', 05:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:53 INFO - 'MOZ_NO_REMOTE': '1', 05:13:53 INFO - 'NO_EM_RESTART': '1', 05:13:53 INFO - 'PAGER': '/bin/cat', 05:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:53 INFO - 'PWD': '/builds/slave/test', 05:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:53 INFO - 'SHELL': '/bin/bash', 05:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:13:53 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:53 INFO - 'USER': 'cltbld', 05:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:53 INFO - 'XPC_FLAGS': '0x0', 05:13:53 INFO - 'XPC_SERVICE_NAME': '0', 05:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:53 INFO - Downloading/unpacking flake8 05:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:13:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:13:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:13:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:56 INFO - no previously-included directories found matching 'docs/_build' 05:13:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:13:56 INFO - Running setup.py install for pep8 05:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:57 INFO - no previously-included directories found matching 'docs/_build' 05:13:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:13:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:13:57 INFO - Cleaning up... 05:13:57 INFO - Return code: 0 05:13:57 INFO - Running post-action listener: _resource_record_post_action 05:13:57 INFO - Running post-action listener: _start_resource_monitoring 05:13:57 INFO - Starting resource monitoring. 05:13:57 INFO - ##### 05:13:57 INFO - ##### Running install step. 05:13:57 INFO - ##### 05:13:57 INFO - Running pre-action listener: _resource_record_pre_action 05:13:57 INFO - Running main action method: install 05:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:57 INFO - Reading from file tmpfile_stdout 05:13:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:57 INFO - Reading from file tmpfile_stdout 05:13:57 INFO - Output received: 05:13:57 INFO - Usage: mozinstall [options] installer 05:13:57 INFO - Options: 05:13:57 INFO - -h, --help show this help message and exit 05:13:57 INFO - -d DEST, --destination=DEST 05:13:57 INFO - Directory to install application into. [default: 05:13:57 INFO - "/builds/slave/test"] 05:13:57 INFO - --app=APP Application being installed. [default: firefox] 05:13:57 INFO - mkdir: /builds/slave/test/build/application 05:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:14:32 INFO - Reading from file tmpfile_stdout 05:14:32 INFO - Output received: 05:14:32 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:14:32 INFO - Running post-action listener: _resource_record_post_action 05:14:32 INFO - ##### 05:14:32 INFO - ##### Running run-tests step. 05:14:32 INFO - ##### 05:14:32 INFO - Running pre-action listener: _resource_record_pre_action 05:14:32 INFO - Running main action method: run_tests 05:14:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:32 INFO - 2016-02-09 05:14:32.637 screenresolution[7163:117865] starting screenresolution argv=screenresolution get 05:14:32 INFO - 2016-02-09 05:14:32.639 screenresolution[7163:117865] Display 0: 1600x1200x32@60 05:14:32 INFO - 2016-02-09 05:14:32.645 screenresolution[7164:117867] starting screenresolution argv=screenresolution list 05:14:32 INFO - Available Modes on Display 0 05:14:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:32 INFO - Intel Iris: 05:14:32 INFO - Chipset Model: Intel Iris 05:14:32 INFO - Type: GPU 05:14:32 INFO - Bus: Built-In 05:14:32 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:32 INFO - Vendor: Intel (0x8086) 05:14:32 INFO - Device ID: 0x0a2e 05:14:32 INFO - Revision ID: 0x0009 05:14:32 INFO - Displays: 05:14:32 INFO - Display: 05:14:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:32 INFO - Display Serial Number: 1600x1200 60 05:14:32 INFO - Main Display: Yes 05:14:32 INFO - Mirror: Off 05:14:32 INFO - Online: Yes 05:14:32 INFO - Rotation: Supported 05:14:32 INFO - Return code: 0 05:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:14:32 INFO - Python 2.7.3 05:14:32 INFO - Return code: 0 05:14:32 INFO - grabbing minidump binary from tooltool 05:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:32 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 0x102149d30>, '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 0x101f6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102140b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:14:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:14:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:14:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:14:32 INFO - Return code: 0 05:14:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:14:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:14:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:14:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:14:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:14:34 INFO - Return code: 0 05:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wZNRUiSCPG/Render', 05:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:34 INFO - 'HOME': '/Users/cltbld', 05:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:34 INFO - 'LOGNAME': 'cltbld', 05:14:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:14:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:34 INFO - 'MOZ_NO_REMOTE': '1', 05:14:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:14:34 INFO - 'NO_EM_RESTART': '1', 05:14:34 INFO - 'PAGER': '/bin/cat', 05:14:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:34 INFO - 'PWD': '/builds/slave/test', 05:14:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:34 INFO - 'SHELL': '/bin/bash', 05:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyFvbPgkHE/Listeners', 05:14:34 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 05:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:34 INFO - 'USER': 'cltbld', 05:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:34 INFO - 'XPC_FLAGS': '0x0', 05:14:34 INFO - 'XPC_SERVICE_NAME': '0', 05:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:14:34 INFO - mozversion application_buildid: 20160209030347 05:14:34 INFO - mozversion application_changeset: d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a 05:14:34 INFO - mozversion application_display_name: Nightly 05:14:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:14:34 INFO - mozversion application_name: Firefox 05:14:34 INFO - mozversion application_remotingname: firefox 05:14:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:14:34 INFO - mozversion application_vendor: Mozilla 05:14:34 INFO - mozversion application_version: 47.0a1 05:14:34 INFO - mozversion platform_buildid: 20160209030347 05:14:34 INFO - mozversion platform_changeset: d1a54ae63da7ebc4bc1eeb5b613e8ec29bfcb80a 05:14:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:14:34 INFO - mozversion platform_version: 47.0a1 05:14:34 INFO - using testdate: 1455023674 05:14:34 INFO - actual date: 1455023674 05:14:34 INFO - starting webserver on 'localhost:58016' 05:14:34 INFO - SUITE-START | Running 2 tests 05:14:34 INFO - TEST-START | dromaeo_css 05:14:34 INFO - operating with platform_type : mac_ 05:14:34 INFO - Initialising browser for dromaeo_css test... 05:14:34 INFO - TEST-INFO | started process 7182 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpviAZFz/profile http://localhost:58016/getInfo.html) 05:14:37 INFO - PROCESS | 7182 | __metrics Screen width/height:1600/1200 05:14:37 INFO - PROCESS | 7182 | colorDepth:24 05:14:37 INFO - PROCESS | 7182 | Browser inner width/height: 1024/689 05:14:37 INFO - PROCESS | 7182 | __metrics 05:14:37 INFO - PROCESS | 7182 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:14:37 INFO - PROCESS | 7182 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:14:37 INFO - TEST-INFO | 7182: exit 0 05:14:37 INFO - Browser initialized. 05:14:37 INFO - Running cycle 1/1 for dromaeo_css test... 05:14:37 INFO - TEST-INFO | started process 7185 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpviAZFz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:49 INFO - PROCESS | 7185 | RSS: Main: 245735424 05:14:49 INFO - PROCESS | 7185 | 05:15:41 INFO - PROCESS | 7185 | 1455023741095 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:17:51 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58016/tests/dromaeo/cssquery-ext.html) 05:17:53 INFO - PROCESS | 7185 | RSS: Main: 380805120 05:17:53 INFO - PROCESS | 7185 | 05:20:55 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-ext.html (next: http://localhost:58016/tests/dromaeo/cssquery-jquery.html) 05:20:56 INFO - PROCESS | 7185 | RSS: Main: 382783488 05:20:56 INFO - PROCESS | 7185 | 05:23:58 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58016/tests/dromaeo/cssquery-mootools.html) 05:23:59 INFO - PROCESS | 7185 | RSS: Main: 380579840 05:23:59 INFO - PROCESS | 7185 | 05:27:01 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58016/tests/dromaeo/cssquery-prototype.html) 05:27:02 INFO - PROCESS | 7185 | RSS: Main: 380854272 05:27:02 INFO - PROCESS | 7185 | 05:30:05 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58016/tests/dromaeo/cssquery-yui.html) 05:30:05 INFO - PROCESS | 7185 | RSS: Main: 358281216 05:30:06 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | Cycle 1(1): loaded http://localhost:58016/tests/dromaeo/cssquery-yui.html (next: http://localhost:58016/tests/dromaeo/cssquery-dojo.html) 05:33:08 INFO - PROCESS | 7185 | RSS: Main: 453218304 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | __start_tp_report 05:33:08 INFO - PROCESS | 7185 | _x_x_mozilla_page_load 05:33:08 INFO - PROCESS | 7185 | _x_x_mozilla_page_load_details 05:33:08 INFO - PROCESS | 7185 | |i|pagename|runs| 05:33:08 INFO - PROCESS | 7185 | |0;dojo.html;3393.2195595013145;3423.6234014258443;3443.603985541662;3460.031191266446;3488.9476657850123;5602.1596760485845;5645.096784514479;5674.0921452567645;5681.602287839853;5711.114777209539;4666.76666166692;5728.856778580537;5734.111212762028;5742.167385729067;5815.302163740346;5071.746412679369;5122.026814905165;5134.974325128369;5151.356080489939;5160.974195129019;4677.2750223715375;5232.3459567554055;5234.214867769827;5259.710715910624;5306.893862122755;5063.139266324733;5086.949130508703;5177.818776342848;5190.740462976836;5198;5163.122269214243;5163.664361816507;5166.870828229286;5172.818951336685;5193.065248255312;4768.833090841838;4781.756743246746;5117.309163262949;5137.588992880561;5170.146925757245;5470.699111548864;5591.608587398884;5607.663540187561;5618.4662457066925;5618.971905140448;6132.600268413486;6147.0779383092895;6165.969170154165;6170;6176.84557886052;6207.87584248317;6234.532410069263;6242.96878515609;6273.435390814803;6284.528660350492;5775.3935836736955;5778.075507918755;5788.986927287708;5789.421057894177;5801.883962320772;6169.629822210682;6170.259568851767;6214.223222097238;6261.467775239064;6289.088082228142;6111.877762444727;6143.754249830046;6167.198264225712;6189.442950134419;6199.101130336076;6266.279377871603;6266.937330626726;6284.088807123033;6284.528660350401;6285.057241413733;6137.938620613826;6149.754009839557;6187.752489900444;6197.25632924043;6218.720157592919;6174.598651087665;6180.536459765535;6204.81385558434;6274.937250627527;6290.433860952537;4903.313536104948;4911.189653707098;4929.162042452792;4933.4819843915875;4948.70307781527;4334.1981733379425;4423.911521769611;4427.092446048617;4465.084657645175;4470.731756094645;4719.669623126349;5596.0206963781175;5687.40282270364;5744.856378590569;5772.884542309131;6244.843878902973;6264.718087685974;6268.561200715997;6277.058441233851;6288.968555157195;5606.60753747242;5682.289713785777;5690.4025077367105;5710.3433105193435;5731.312242530923;5356.571474282048;5396.298481197419;5417.783288668409;5426.9185962210595;5431.945680543223;6213.751449942042;6257.624542527463;6288;6323.747050118036;6352.587081839665;6222.719977600928;6262.812115636538;6263.78076767311;6265.530085243716;6266.342034086355;6337.144485494399;6372.107904893412;6372.394622510854;6380.074889140956;6394.936050639434;6052.061930400795;6114.723190878713;6116;6181.721822518088;6189.35011823752;5157.7679004444135;6226.5641405101205;6230.968845155745;6237.407446292595;6251.562390632794;5938.851528711644;6052.90920636182;6131.724072416841;6136.7852125175405;6141.508679305734;6010.42900924429;6080.148779171008;6124.594542075475;6124.991279909774;6133.426475724657;5955.851103722442;5961.4038596140035;5969.104634304889;5979.282486101695;6001.219841420588;5123.718195499134;5982.1325907744;6087.147799308189;6110.572259941761;6112.219960280447;5817.301923769175;5843.912341314799;5855.180274761452;5894.174815525744;5896.469317761422 05:33:08 INFO - PROCESS | 7185 | |1;ext.html;417.73056378635783;519.0475477498794;550.6613432738867;552.4475524475524;665.7436886798582;29634.851825740894;37182.81408592957;37552;37654.24691506168;37695.05762355943;40082;40478.392824107665;40535;40572;40626.796866015706;9307.162355388014;9541.522923853814;9545.14093731564;9545.856812147824;9555.713328600154;9442.952785236064;9543.856842147374;9589.712308630717;9604;9741.951290243538;9716.417014979088;9780.168685661727;9794.461304628243;9799.559019844124;9810.950945245264;9661.516924153764;9741.951290243574;9749.951250243703;9804.117629413317;9886;9563.665271715456;9700.805983880353;9712.320137590372;9718.951405242999;9725.221982241474;39107.60892391068;39537;39553.80223098895;39554.208915821524;39641;1879.7723039297118;3199.3921154980712;3307.4212012897888;3327.5008748687646;3384.4077286475012;25929.74070259311;26851.731482685118;27199.45601087987;27364.58953115705;27415.862920685267;2729.972700272992;2756.0629386008854;2816.3944751878444;2846.8291902485917;2847.7437030667134;335885;336759;459651;460338;505142;50293.74853125711;50329.49670503321;50974.49025509771;50976.74511627418;50978.23532647013;53014.204786928225;53030.469695302556;53155.468445315055;53267;53315.73342133342;15839.604009899846;16013.919930400274;16019.919900400424;16024;16027.439039633557;12462.501499940083;12483.875161248452;12513.74972500545;12559.811602825965;12577.685557861127;19494.415167544998;19755.901220493804;19776.802231977785;19786.802131978497;19798.70301945472;7398.741044063432;7934.761957141295;8460.576971151419;8471.449355791854;8499.447535910145;983.5721461164417;1045.7333379988054;1130.6947124276564;1139.5441823270592;1190.357207108152;29220.123396298146;29591.408171836872;29699.554506682416;30279.394412112077;30429.391412171633;1031.6492392586406;1066.9573217071386;1101.146611376174;1104.5305745058317;1127.2785417332914;15046.924765376103;15106.924465377602;15218.239088045775;15593.688126237412;15629.374825006873;658092;658607;658757;664804;665294;57247.71376143176;57953.710231448575;58009.709951450815;58103.12845307324;58116.12825807616;1406.3038795796147;1422.295963498075;1444.6460617148866;1445.4435042508499;1450.5068276785944;29136.7086329134;29240.853795730884;29244;29968.85015574908;29976.850115749283;50221;50623;50628.49371506238;50720;50814.74592627013;480336;484299;485235;485533;486437;34854.82572587121;35434.468482973294;41238.38142427806;41672.58327416687;42079.57920420756;41058.5629502966;41489.58510414978;41792;42150;42195.94510137271;29330.413391732473;34089.659103408645;34434.48348274728;35392.93821185369;35471.64528354684;34357.3405963127;34841.07701690769;35105.82447087748;35159.12102197466;35223;33814.830925845214;34626;34956.82521587376;36424.63575364213;36440.81779591085;29865.402691945605;31505.52741708922;31575.368492630478;31586.210344741565;31938.36123277568;24472.632910506;24527.509449810546;24834.751652483243;25039.499210016063;25070.74929250757 05:33:08 INFO - PROCESS | 7185 | |2;jquery.html;313.7709472085379;333.5630324275199;343.6975461593798;364.87594217965886;368.4418106568547;14643.853561464382;14861.55415337539;18657.813421865776;18697.813021869777;19877.105530251156;11723.648290551277;12103.818442723345;15227.16250606219;18587.814121858777;18645.813541864576;5252.343457067866;5378.542823859977;5380.166074258476;5384.730763461831;5393.78424863005;3935;4098.385242213662;5117.8464646060675;5220.190870415092;7154.892676609855;7192.604406757626;7291.52605080668;7472.701091956315;7479.551226926402;7479.962600187018;7310.049693539806;7367.1527774306105;7372.557646541225;7382.704691812348;7385.670579295752;6855.725770969155;7052.823679408005;7066.92933070673;7067.717291308341;7068.611226382547;26166.73833261662;26187.73812261872;26267.080652177083;27107.780149893104;27617.03340383077;2323.233333000123;2388.4267775733874;2604.7004594471687;2798.5802129680505;3223.468127758917;15504.844951550453;15589.844101559067;15612.76580851288;15641.608959775982;16163.272652730655;1903.1245627011592;1939.8060193980489;1959.1869374209687;2042.428120126367;2111.799379058987;68340.31659683389;92380;97615;98008;99558.50220748922;18406.723899141525;19167.424977250706;19255.51861203481;19397;19451;25973.090941817325;27456.90172393122;28019;28033.859830701123;28075.43849123006;8661.220490155916;10614;10625.5218515167;10693.358398496115;10728.89271107279;9054.139856713733;9072.410293330913;9144;9203.585838637277;9210.355275130676;7070.566097665431;8276.758486227114;8482.533460659599;8541.743747687578;8662;3280.557124788123;3410.5736782902136;3412.129906873731;3462.7576069674883;3463.5324231228965;863.5811631358786;917.554985831871;933.7898972731081;993.6671215143008;1054.9050585447346;15298.770518442232;15322.463713769965;17961.1019449027;18230.63538729218;19896.60206795856;850.2985037344092;978.6232300564334;985.7683444390562;1003.2776400991274;1020.1023099672241;10160.33957792741;10509.316894401993;10581.523831427605;10626.516952489068;10692.251542392114;650793;688416;690693;734475;742598;104572;105182.79268829314;110178;110261;123672;1056.6844278872852;1138.8861113888545;1200.8679045305184;1237.3194742891374;1285.3252042677668;17517.737233941756;18041.278348865897;18067.91592504454;18195.90824550531;18350.72473912919;50699.746501267255;51197;51687.449376518765;51954;52760;67906.32093679;69090;69306;70009.29990700028;70966;7356.742514011984;7675;7759.418043646636;7763.92236077632;7862.685492580233;9763.609455621696;11896.286222826659;12190.329531875832;12191.146619736533;12617.116801824151;10258.640947566799;10474.738131546772;10483.633072842415;10663.253572250178;10816.296940698829;10100.343477673927;10427.322224055411;10558.155347572329;10590.576376944837;10610.045095941403;10591.311564748266;10642.680719578426;10645.0951669109;10759.89240107589;10851;6273.404026617464;7609.6575654094595;7626.8855967159425;7637.159912409742;7653.425993050655;9058.139476749699;10422.791544169226;10487.793903701173;10629.415382154055;10648.148148148284 05:33:08 INFO - PROCESS | 7185 | |3;mootools.html;1370.390176371515;1494.551634509647;1535.784990101386;1750.8249175082494;1922.4424916774128;8011.158828323022;8682.871226740523;8831.072737362581;8841.204291613745;8846.248068914134;8412.284955778767;8720.956395218014;8740.956295218532;8742;8742.344324175696;2148.581026699794;2212.590670725921;2215.058600094961;2461.667674863893;2489.3776555861036;2138.4119367173994;2150.354893531942;2153.06341741343;2434.087217293515;2497.8001759859167;2411.247872719365;2443.1815341860497;2447.0945750072497;2449.644801503781;2453.58289090855;2440.6217036359217;2455.5579995600783;2464.3346296499935;2467.716212635534;2467.987660061688;2068.8758674479577;2389.3787615219994;2404.386881345263;2427.1626288930293;2428.7449817769234;8897.243734282594;8988.59551320192;8994.910050899472;8999.910000899972;9007.909920900773;5367.543758780508;5511.917321240185;5543.473370029841;5546.69493177875;5551.944480555183;3614.204874927512;3632.5640923089136;3783.6027217142355;3794.8102594870147;3798.1833905710128;1019.4902548725637;1023.8668973033532;1044.8276034454307;1068.6793961811425;1083.8049151152868;12294.631161065183;12296.692582685415;12315.56895508662;12322.445489952885;12326.383680815923;4897.681650692693;4929.901401971941;4959.181735013754;4983.700977941263;5026.447090820006;10335.844962325571;10340.482975851177;10347;10347.793044139074;10357.4303413313;5524.171374293807;5528.917066244009;5530.5299049580435;5535.58483113768;5543.36251331094;5009.5992320615;5017.473165317662;5019.121653710659;5022;5026.447090820006;3660.213054193361;3910.7066969977363;3943.566207717206;3946.427767973628;3997.380406037069;1202;1218.6100447856777;1219.945102470391;1221.407617305606;1270.060155484953;1288.1240756285815;1291.231717128307;1332.7201287729492;1333.7732585460494;1335.7729186038396;7991.4006449515355;8209.179082091743;8350.749477515685;8356.331493480508;8384.413091083688;1152.6772503698978;1169.6958790714305;1172.7185475485821;1187.75057237981;1191.7854786138405;6320.905186422207;6352.269489008823;6404.103425520431;6406;6408.391202835724;10439.843402348972;10798.892011079946;10813.459327033774;10928.672139835817;10950.835737463944;8935.329850261127;10287.279890407599;10867.78264434707;10933.125349971982;11027.944860275647;8137.267645911897;8346.582670866432;8358.414910956297;8361.993121650881;8382.622781974826;7986.600669966595;8122.675092996214;8171.918280817354;8185.017797864294;8214.63034163476;9403.4828084456;9501.619935202514;9644.614215431304;9660.75848103803;9686.70939871805;9710.805783884423;9936.354136981074;10171.23715721309;10278.794424111626;10298.845517317395;7726.845463090594;7745.457817952689;7798.103218129988;7808.726694565663;7864.449488535747;8348.6243119061;8442.704505342284;8446.408751387344;8459.915400845914;8461.703840365557;6795.592264464148;7109.395701365442;7201.531900426456;7826.882596760939;7981.920180798118;7256.383207427428;7623.352015078781;7713.2286771322415;7738.690452381842;7793.06483222017;7449.292317229854;7745.612719364122;7750.10873749526;7771.455998080306;7803.609819509115;7076.787696369117;7125.465590080661;7126.251743567058;7126.786196414116;7136.5004449690105;7113.466490013167;7201.71193152268;7205.747798827016;7225.891611625935;7226.674799633924 05:33:08 INFO - PROCESS | 7185 | |4;prototype.html;321.55946353495716;328.829008915364;332.92509185433295;333.3349966816202;336.92756057447644;5182.974085129578;6434.3565643435695;6439.259485159208;6464.385883341075;6520.7391704331885;6067.726952287147;6080.452759251666;6239.282482514513;6274.341194174603;6433.099366088752;4735.881602959919;4742.241241401376;4789.616830653539;4797.472278049412;4805.735684537349;4577.9542204577865;4787.9521204788025;4804.855854324358;4808.63935204861;4816.54242846929;4631.212693842038;4660.580547750702;4729.432468103832;4746.430428348603;4754.429468463772;4740.241561350184;4746.4541577718655;4747.287906813971;4756.357891684647;4768.904621907543;4098.754074755494;4102.897427564305;4102.979485102555;4105.3020986432375;4110.403991421257;5961.821145365646;6052.576319657625;6139.079138129272;6170.8765824683705;6190.690465476753;5608.2428872102155;5613.494785469327;5619.859503512404;5622.49397554218;5630.042892708249;6854.383105520527;6867.07294515239;6867.965660171667;6873.862522749517;6874.931250687529;4598.379218805452;4598.609118224921;4599.839005634787;4600.562946520109;4607.170709272363;6885.690143943534;7250.311220434103;7276.781696549112;7282.890756638654;7290.59901705406;4696.459907110658;4705.223638099744;4709.552592503706;4712.858614241578;4718;4761.4524329701835;4776.785044672998;4784.808607655655;4787.569118779326;4800.591949684316;5041.395032596039;5144.948550514447;5590.301212348457;5590.91613625796;5595.356533998643;6837.863242735217;6848;6848.417884479775;6851.314868513208;6858.142732158481;1643.6137507685685;1673.3306677329165;1675.0284834795789;1675.7570152328087;1677.1446562253095;1083.550326614462;1087.6138970665459;1107.479484642217;1108.578740078781;1118.6532175025768;6335.049742538656;6340.873182536324;6349.46029587481;6367.840803979938;6373.681315934185;6837.726490940307;7056.470764692566;7092.4680648951535;7096.361327480551;7105.893411598727;6273.9372606273355;6418.743250269937;6445.613263204129;6450.935595626763;6495.480361571157;3725.2735716535017;3760.9435858462143;3763;3767.6609105180664;3768.660820526111;7310.7075716971785;7491.138519070268;7502.2122677119205;7529.8117547060665;7543.245675432413;7358.227386124487;7370.152432470228;7423.740169094056;7450.994115794407;7456.1798202197715;7493.100827900686;7505.66224519887;7515.286047825448;7516.774496765106;7532;6904.827379315558;7104.325089116525;7105.857882842418;7110.537815042006;7125.073740413713;7016.052832867497;7017.228104908354;7029.191642961125;7046.823829404306;7123.643817809192;7264.091988501437;7545.849083018418;7585.596664617064;7591.962040189764;7595.126560445398;4528.705634133903;4580.152671755736;4585.403897493305;4588.609968152745;4592.242280023758;6586.176727909012;7172.964135179291;7180.640967951686;7194.568325900463;7196.352328290479;6688.565243259172;6957.547759395826;6961.965190174017;6962.060121883481;6975.128108986377;5941.8116376723965;6712.563683360566;6891.483138764513;6919.34266244726;6926.515143939876;6765.627890466071;6767.289434609295;6770.898436523357;6771.356721111487;6772.93227067723;5612.382637909908;5727.828165155052;6411.294757576756;6922.411595014481;6942.444604431533;6492.967535162294;6715.3620406061345;6761.763338283136;6764;6789.219239787488 05:33:08 INFO - PROCESS | 7185 | |5;yui.html;678.1421501800221;679.962602056887;710.8329542557498;728.0644371982004;737.1890919988017;14495.130292182463;14648.633784155401;14663.706725865502;14671.339789709462;14680.632984175387;15252.38990440381;15615.687686246269;15622.687546249068;15647.687046259069;15954.441594544192;678.1556961582836;691.332863786446;839.1985653700701;856.2507805670039;856.9014563325235;830.1366578758115;833.7582101190648;847.6100993542977;848.6350869126273;849.3332733803965;895.9417637853551;897.6544030548221;899.9280057595377;900.729781065681;900.8153328567661;707.1584814071239;725.5646612032791;867.4058270085012;884.8893888263967;890.5502721125822;615.5075939248583;630.6972653126479;657.3623585122424;658.5686375424104;659.986800263992;14672.853271467255;14826.962112652118;14838.109713417121;14850.03474774147;14874.033187842755;12296.631101066982;12343.567975120917;12365.010799136138;12404.13171078025;12676.556320528738;10113.949430252802;10120.829396847541;10149.191216067995;10155.949220253851;10222;3418.5555877735737;3448.6378930212218;3455.2053027803745;3457.913552161191;3467.653234676512;79861;80352;82957;83030.58484707538;83365.99999999939;9202.217811486098;9279.39683920543;9281.257499400032;9467.100625440573;9508.524573771421;20330.796692033186;20346.28787992413;20442.08010639525;20444.488887777923;20449.48876278105;7406.7777966660005;7414;7459.776206713807;7609.733659321897;7701.691932322644;4116.608922152391;4165.187788381297;4169.478815148106;4171.97914010434;4189.937150942738;3544.80503572306;3580.1407662160896;3586.9282614347567;3592.137886907176;3598.1004748812798;633.4995353670548;642.7300533775825;653.1052458132389;659.1069101367674;673.9755571531246;523.5680563535099;587.2776484923568;596.9611975221596;617.3425302053299;623.3236937922314;8041.678332866737;8698.869516957251;8785.51679657625;8916.420432671855;9084.364094513452;579.4413028952106;603.0983679399326;609.326694003129;618.1994317359031;619.1734035063241;3723.236736469017;3764.322421964018;3899.395593682984;3964.5044369453817;4046.5144182697677;83328.26330881436;83750.58124709337;85007.57496212603;85146;85636.57181714052;75166;75202.11994700067;75560;75638;76286;67434.65130697457;67493.32506674969;69215;69298.65350673316;69712.6514367425;6548.443382312556;8229.629666665134;8536.871946920925;8664.436811607226;8891.244244239311;48657.75671121763;48759.75620121877;49025.5097449021;50190;50521.24218136657;64676;66664;66742;68384;69315.65342173257;11181.832272516082;11381.829272560753;11642.41787910618;11642.825357619473;11718.296902185555;11746.353950532803;12112.333821639895;12370.591941108281;12656;12760;11037.558497660004;11487.827682584935;11530;11765.524784965017;12355.32045737493;11248.662540123809;11856.11079169083;11931.985781208694;11963.76072478563;12325.876741232472;10468.267221294436;11472.713182170513;11525.252375076436;11721.706957326134;11845.407729613657;9215.539223038688;9326.580303886478;9359.85960210611;9453.33826632129;9929.453880036668;7453.850922981402;8970.282377409661;9128.269738420777;9144.862827057466;9837.311388202757 05:33:08 INFO - PROCESS | 7185 | __end_tp_report 05:33:08 INFO - PROCESS | 7185 | __start_cc_report 05:33:08 INFO - PROCESS | 7185 | _x_x_mozilla_cycle_collect,1291 05:33:08 INFO - PROCESS | 7185 | __end_cc_report 05:33:08 INFO - PROCESS | 7185 | __startTimestamp1455024788954__endTimestamp 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | ------- Summary: start ------- 05:33:08 INFO - PROCESS | 7185 | Number of tests: 6 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#0] dojo.html Cycles:165 Average:5704.06 Median:5938.85 stddev:653.71 (11.0%) stddev-sans-first:630.08 05:33:08 INFO - PROCESS | 7185 | Values: 3393.2 3423.6 3443.6 3460.0 3488.9 5602.2 5645.1 5674.1 5681.6 5711.1 4666.8 5728.9 5734.1 5742.2 5815.3 5071.7 5122.0 5135.0 5151.4 5161.0 4677.3 5232.3 5234.2 5259.7 5306.9 5063.1 5086.9 5177.8 5190.7 5198.0 5163.1 5163.7 5166.9 5172.8 5193.1 4768.8 4781.8 5117.3 5137.6 5170.1 5470.7 5591.6 5607.7 5618.5 5619.0 6132.6 6147.1 6166.0 6170.0 6176.8 6207.9 6234.5 6243.0 6273.4 6284.5 5775.4 5778.1 5789.0 5789.4 5801.9 6169.6 6170.3 6214.2 6261.5 6289.1 6111.9 6143.8 6167.2 6189.4 6199.1 6266.3 6266.9 6284.1 6284.5 6285.1 6137.9 6149.8 6187.8 6197.3 6218.7 6174.6 6180.5 6204.8 6274.9 6290.4 4903.3 4911.2 4929.2 4933.5 4948.7 4334.2 4423.9 4427.1 4465.1 4470.7 4719.7 5596.0 5687.4 5744.9 5772.9 6244.8 6264.7 6268.6 6277.1 6289.0 5606.6 5682.3 5690.4 5710.3 5731.3 5356.6 5396.3 5417.8 5426.9 5431.9 6213.8 6257.6 6288.0 6323.7 6352.6 6222.7 6262.8 6263.8 6265.5 6266.3 6337.1 6372.1 6372.4 6380.1 6394.9 6052.1 6114.7 6116.0 6181.7 6189.4 5157.8 6226.6 6231.0 6237.4 6251.6 5938.9 6052.9 6131.7 6136.8 6141.5 6010.4 6080.1 6124.6 6125.0 6133.4 5955.9 5961.4 5969.1 5979.3 6001.2 5123.7 5982.1 6087.1 6110.6 6112.2 5817.3 5843.9 5855.2 5894.2 5896.5 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#1] ext.html Cycles:180 Average:65288.44 Median:37603.12 stddev:142555.09 (379.1%) stddev-sans-first:142871.79 05:33:08 INFO - PROCESS | 7185 | Values: 417.7 519.0 550.7 552.4 665.7 29634.9 37182.8 37552.0 37654.2 37695.1 40082.0 40478.4 40535.0 40572.0 40626.8 9307.2 9541.5 9545.1 9545.9 9555.7 9443.0 9543.9 9589.7 9604.0 9742.0 9716.4 9780.2 9794.5 9799.6 9811.0 9661.5 9742.0 9750.0 9804.1 9886.0 9563.7 9700.8 9712.3 9719.0 9725.2 39107.6 39537.0 39553.8 39554.2 39641.0 1879.8 3199.4 3307.4 3327.5 3384.4 25929.7 26851.7 27199.5 27364.6 27415.9 2730.0 2756.1 2816.4 2846.8 2847.7 335885.0 336759.0 459651.0 460338.0 505142.0 50293.7 50329.5 50974.5 50976.7 50978.2 53014.2 53030.5 53155.5 53267.0 53315.7 15839.6 16013.9 16019.9 16024.0 16027.4 12462.5 12483.9 12513.7 12559.8 12577.7 19494.4 19755.9 19776.8 19786.8 19798.7 7398.7 7934.8 8460.6 8471.4 8499.4 983.6 1045.7 1130.7 1139.5 1190.4 29220.1 29591.4 29699.6 30279.4 30429.4 1031.6 1067.0 1101.1 1104.5 1127.3 15046.9 15106.9 15218.2 15593.7 15629.4 658092.0 658607.0 658757.0 664804.0 665294.0 57247.7 57953.7 58009.7 58103.1 58116.1 1406.3 1422.3 1444.6 1445.4 1450.5 29136.7 29240.9 29244.0 29968.9 29976.9 50221.0 50623.0 50628.5 50720.0 50814.7 480336.0 484299.0 485235.0 485533.0 486437.0 34854.8 35434.5 41238.4 41672.6 42079.6 41058.6 41489.6 41792.0 42150.0 42195.9 29330.4 34089.7 34434.5 35392.9 35471.6 34357.3 34841.1 35105.8 35159.1 35223.0 33814.8 34626.0 34956.8 36424.6 36440.8 29865.4 31505.5 31575.4 31586.2 31938.4 24472.6 24527.5 24834.8 25039.5 25070.7 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#2] jquery.html Cycles:180 Average:37001.45 Median:15408.79 stddev:115383.26 (748.8%) stddev-sans-first:115674.06 05:33:08 INFO - PROCESS | 7185 | Values: 313.8 333.6 343.7 364.9 368.4 14643.9 14861.6 18657.8 18697.8 19877.1 11723.6 12103.8 15227.2 18587.8 18645.8 5252.3 5378.5 5380.2 5384.7 5393.8 3935.0 4098.4 5117.8 5220.2 7154.9 7192.6 7291.5 7472.7 7479.6 7480.0 7310.0 7367.2 7372.6 7382.7 7385.7 6855.7 7052.8 7066.9 7067.7 7068.6 26166.7 26187.7 26267.1 27107.8 27617.0 2323.2 2388.4 2604.7 2798.6 3223.5 15504.8 15589.8 15612.8 15641.6 16163.3 1903.1 1939.8 1959.2 2042.4 2111.8 68340.3 92380.0 97615.0 98008.0 99558.5 18406.7 19167.4 19255.5 19397.0 19451.0 25973.1 27456.9 28019.0 28033.9 28075.4 8661.2 10614.0 10625.5 10693.4 10728.9 9054.1 9072.4 9144.0 9203.6 9210.4 7070.6 8276.8 8482.5 8541.7 8662.0 3280.6 3410.6 3412.1 3462.8 3463.5 863.6 917.6 933.8 993.7 1054.9 15298.8 15322.5 17961.1 18230.6 19896.6 850.3 978.6 985.8 1003.3 1020.1 10160.3 10509.3 10581.5 10626.5 10692.3 650793.0 688416.0 690693.0 734475.0 742598.0 104572.0 105182.8 110178.0 110261.0 123672.0 1056.7 1138.9 1200.9 1237.3 1285.3 17517.7 18041.3 18067.9 18195.9 18350.7 50699.7 51197.0 51687.4 51954.0 52760.0 67906.3 69090.0 69306.0 70009.3 70966.0 7356.7 7675.0 7759.4 7763.9 7862.7 9763.6 11896.3 12190.3 12191.1 12617.1 10258.6 10474.7 10483.6 10663.3 10816.3 10100.3 10427.3 10558.2 10590.6 10610.0 10591.3 10642.7 10645.1 10759.9 10851.0 6273.4 7609.7 7626.9 7637.2 7653.4 9058.1 10422.8 10487.8 10629.4 10648.1 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#3] mootools.html Cycles:180 Average:6138.23 Median:5026.45 stddev:3251.57 (64.7%) stddev-sans-first:3240.93 05:33:08 INFO - PROCESS | 7185 | Values: 1370.4 1494.6 1535.8 1750.8 1922.4 8011.2 8682.9 8831.1 8841.2 8846.2 8412.3 8721.0 8741.0 8742.0 8742.3 2148.6 2212.6 2215.1 2461.7 2489.4 2138.4 2150.4 2153.1 2434.1 2497.8 2411.2 2443.2 2447.1 2449.6 2453.6 2440.6 2455.6 2464.3 2467.7 2468.0 2068.9 2389.4 2404.4 2427.2 2428.7 8897.2 8988.6 8994.9 8999.9 9007.9 5367.5 5511.9 5543.5 5546.7 5551.9 3614.2 3632.6 3783.6 3794.8 3798.2 1019.5 1023.9 1044.8 1068.7 1083.8 12294.6 12296.7 12315.6 12322.4 12326.4 4897.7 4929.9 4959.2 4983.7 5026.4 10335.8 10340.5 10347.0 10347.8 10357.4 5524.2 5528.9 5530.5 5535.6 5543.4 5009.6 5017.5 5019.1 5022.0 5026.4 3660.2 3910.7 3943.6 3946.4 3997.4 1202.0 1218.6 1219.9 1221.4 1270.1 1288.1 1291.2 1332.7 1333.8 1335.8 7991.4 8209.2 8350.7 8356.3 8384.4 1152.7 1169.7 1172.7 1187.8 1191.8 6320.9 6352.3 6404.1 6406.0 6408.4 10439.8 10798.9 10813.5 10928.7 10950.8 8935.3 10287.3 10867.8 10933.1 11027.9 8137.3 8346.6 8358.4 8362.0 8382.6 7986.6 8122.7 8171.9 8185.0 8214.6 9403.5 9501.6 9644.6 9660.8 9686.7 9710.8 9936.4 10171.2 10278.8 10298.8 7726.8 7745.5 7798.1 7808.7 7864.4 8348.6 8442.7 8446.4 8459.9 8461.7 6795.6 7109.4 7201.5 7826.9 7981.9 7256.4 7623.4 7713.2 7738.7 7793.1 7449.3 7745.6 7750.1 7771.5 7803.6 7076.8 7125.5 7126.3 7126.8 7136.5 7113.5 7201.7 7205.7 7225.9 7226.7 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#4] prototype.html Cycles:180 Average:5649.05 Median:6345.17 stddev:1776.65 (28.0%) stddev-sans-first:1736.06 05:33:08 INFO - PROCESS | 7185 | Values: 321.6 328.8 332.9 333.3 336.9 5183.0 6434.4 6439.3 6464.4 6520.7 6067.7 6080.5 6239.3 6274.3 6433.1 4735.9 4742.2 4789.6 4797.5 4805.7 4578.0 4788.0 4804.9 4808.6 4816.5 4631.2 4660.6 4729.4 4746.4 4754.4 4740.2 4746.5 4747.3 4756.4 4768.9 4098.8 4102.9 4103.0 4105.3 4110.4 5961.8 6052.6 6139.1 6170.9 6190.7 5608.2 5613.5 5619.9 5622.5 5630.0 6854.4 6867.1 6868.0 6873.9 6874.9 4598.4 4598.6 4599.8 4600.6 4607.2 6885.7 7250.3 7276.8 7282.9 7290.6 4696.5 4705.2 4709.6 4712.9 4718.0 4761.5 4776.8 4784.8 4787.6 4800.6 5041.4 5144.9 5590.3 5590.9 5595.4 6837.9 6848.0 6848.4 6851.3 6858.1 1643.6 1673.3 1675.0 1675.8 1677.1 1083.6 1087.6 1107.5 1108.6 1118.7 6335.0 6340.9 6349.5 6367.8 6373.7 6837.7 7056.5 7092.5 7096.4 7105.9 6273.9 6418.7 6445.6 6450.9 6495.5 3725.3 3760.9 3763.0 3767.7 3768.7 7310.7 7491.1 7502.2 7529.8 7543.2 7358.2 7370.2 7423.7 7451.0 7456.2 7493.1 7505.7 7515.3 7516.8 7532.0 6904.8 7104.3 7105.9 7110.5 7125.1 7016.1 7017.2 7029.2 7046.8 7123.6 7264.1 7545.8 7585.6 7592.0 7595.1 4528.7 4580.2 4585.4 4588.6 4592.2 6586.2 7173.0 7180.6 7194.6 7196.4 6688.6 6957.5 6962.0 6962.1 6975.1 5941.8 6712.6 6891.5 6919.3 6926.5 6765.6 6767.3 6770.9 6771.4 6772.9 5612.4 5727.8 6411.3 6922.4 6942.4 6493.0 6715.4 6761.8 6764.0 6789.2 05:33:08 INFO - PROCESS | 7185 | 05:33:08 INFO - PROCESS | 7185 | [#5] yui.html Cycles:180 Average:17993.94 Median:627.01 stddev:24873.69 (3967.0%) stddev-sans-first:24909.49 05:33:08 INFO - PROCESS | 7185 | Values: 678.1 680.0 710.8 728.1 737.2 14495.1 14648.6 14663.7 14671.3 14680.6 15252.4 15615.7 15622.7 15647.7 15954.4 678.2 691.3 839.2 856.3 856.9 830.1 833.8 847.6 848.6 849.3 895.9 897.7 899.9 900.7 900.8 707.2 725.6 867.4 884.9 890.6 615.5 630.7 657.4 658.6 660.0 14672.9 14827.0 14838.1 14850.0 14874.0 12296.6 12343.6 12365.0 12404.1 12676.6 10113.9 10120.8 10149.2 10155.9 10222.0 3418.6 3448.6 3455.2 3457.9 3467.7 79861.0 80352.0 82957.0 83030.6 83366.0 9202.2 9279.4 9281.3 9467.1 9508.5 20330.8 20346.3 20442.1 20444.5 20449.5 7406.8 7414.0 7459.8 7609.7 7701.7 4116.6 4165.2 4169.5 4172.0 4189.9 3544.8 3580.1 3586.9 3592.1 3598.1 633.5 642.7 653.1 659.1 674.0 523.6 587.3 597.0 617.3 623.3 8041.7 8698.9 8785.5 8916.4 9084.4 579.4 603.1 609.3 618.2 619.2 3723.2 3764.3 3899.4 3964.5 4046.5 83328.3 83750.6 85007.6 85146.0 85636.6 75166.0 75202.1 75560.0 75638.0 76286.0 67434.7 67493.3 69215.0 69298.7 69712.7 6548.4 8229.6 8536.9 8664.4 8891.2 48657.8 48759.8 49025.5 50190.0 50521.2 64676.0 66664.0 66742.0 68384.0 69315.7 11181.8 11381.8 11642.4 11642.8 11718.3 11746.4 12112.3 12370.6 12656.0 12760.0 11037.6 11487.8 11530.0 11765.5 12355.3 11248.7 11856.1 11932.0 11963.8 12325.9 10468.3 11472.7 11525.3 11721.7 11845.4 9215.5 9326.6 9359.9 9453.3 9929.5 7453.9 8970.3 9128.3 9144.9 9837.3 05:33:08 INFO - PROCESS | 7185 | -------- Summary: end -------- 05:33:08 INFO - PROCESS | 7185 | 05:33:09 INFO - TEST-INFO | 7185: exit 0 05:33:09 INFO - TEST-OK | dromaeo_css | took 1114703ms 05:33:09 INFO - TEST-START | kraken 05:33:09 INFO - operating with platform_type : mac_ 05:33:09 INFO - Initialising browser for kraken test... 05:33:09 INFO - TEST-INFO | started process 7188 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpTR360a/profile http://localhost:58016/getInfo.html) 05:33:11 INFO - PROCESS | 7188 | __metrics Screen width/height:1600/1200 05:33:11 INFO - PROCESS | 7188 | colorDepth:24 05:33:11 INFO - PROCESS | 7188 | Browser inner width/height: 1024/689 05:33:11 INFO - PROCESS | 7188 | __metrics 05:33:11 INFO - PROCESS | 7188 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:33:11 INFO - PROCESS | 7188 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:12 INFO - TEST-INFO | 7188: exit 0 05:33:12 INFO - Browser initialized. 05:33:12 INFO - Running cycle 1/1 for kraken test... 05:33:12 INFO - TEST-INFO | started process 7191 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpTR360a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:24 INFO - PROCESS | 7191 | RSS: Main: 245424128 05:33:24 INFO - PROCESS | 7191 | 05:33:26 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58016/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:27 INFO - PROCESS | 7191 | RSS: Main: 291184640 05:33:27 INFO - PROCESS | 7191 | 05:33:30 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58016/tests/kraken/driver.html?testName=audio-dft) 05:33:30 INFO - PROCESS | 7191 | RSS: Main: 302129152 05:33:30 INFO - PROCESS | 7191 | 05:33:34 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58016/tests/kraken/driver.html?testName=audio-fft) 05:33:34 INFO - PROCESS | 7191 | RSS: Main: 288841728 05:33:34 INFO - PROCESS | 7191 | 05:33:36 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58016/tests/kraken/driver.html?testName=audio-oscillator) 05:33:37 INFO - PROCESS | 7191 | RSS: Main: 289394688 05:33:37 INFO - PROCESS | 7191 | 05:33:39 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58016/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:33:39 INFO - PROCESS | 7191 | RSS: Main: 292593664 05:33:39 INFO - PROCESS | 7191 | 05:33:42 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58016/tests/kraken/driver.html?testName=imaging-darkroom) 05:33:43 INFO - PROCESS | 7191 | RSS: Main: 293138432 05:33:43 INFO - PROCESS | 7191 | 05:33:46 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58016/tests/kraken/driver.html?testName=imaging-desaturate) 05:33:46 INFO - PROCESS | 7191 | RSS: Main: 291680256 05:33:46 INFO - PROCESS | 7191 | 05:33:49 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58016/tests/kraken/driver.html?testName=json-parse-financial) 05:33:49 INFO - PROCESS | 7191 | RSS: Main: 292499456 05:33:49 INFO - PROCESS | 7191 | 05:33:51 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58016/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:52 INFO - PROCESS | 7191 | RSS: Main: 327856128 05:33:52 INFO - PROCESS | 7191 | 05:33:53 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:54 INFO - PROCESS | 7191 | RSS: Main: 327561216 05:33:54 INFO - PROCESS | 7191 | 05:33:56 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:57 INFO - PROCESS | 7191 | RSS: Main: 334999552 05:33:57 INFO - PROCESS | 7191 | 05:33:59 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:00 INFO - PROCESS | 7191 | RSS: Main: 334553088 05:34:00 INFO - PROCESS | 7191 | 05:34:03 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:03 INFO - PROCESS | 7191 | RSS: Main: 335757312 05:34:03 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | Cycle 1(1): loaded http://localhost:58016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58016/tests/kraken/driver.html?testName=ai-astar) 05:34:05 INFO - PROCESS | 7191 | RSS: Main: 395743232 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | __start_tp_report 05:34:05 INFO - PROCESS | 7191 | _x_x_mozilla_page_load 05:34:05 INFO - PROCESS | 7191 | _x_x_mozilla_page_load_details 05:34:05 INFO - PROCESS | 7191 | |i|pagename|runs| 05:34:05 INFO - PROCESS | 7191 | |0;ai-astar;111;109;113;111;112;110;120;158;164;111 05:34:05 INFO - PROCESS | 7191 | |1;audio-beat-detection;155;119;165;156;141;174;167;120;162;119 05:34:05 INFO - PROCESS | 7191 | |2;audio-dft;185;185;184;190;186;188;185;180;182;182 05:34:05 INFO - PROCESS | 7191 | |3;audio-fft;74;77;86;78;60;60;57;81;77;56 05:34:05 INFO - PROCESS | 7191 | |4;audio-oscillator;93;97;132;79;80;80;113;75;79;110 05:34:05 INFO - PROCESS | 7191 | |5;imaging-gaussian-blur;132;130;84;90;84;89;84;90;84;89 05:34:05 INFO - PROCESS | 7191 | |6;imaging-darkroom;145;102;95;93;94;93;94;93;94;94 05:34:05 INFO - PROCESS | 7191 | |7;imaging-desaturate;101;81;84;84;85;84;85;85;84;84 05:34:05 INFO - PROCESS | 7191 | |8;json-parse-financial;56;55;55;53;53;55;54;63;52;53 05:34:05 INFO - PROCESS | 7191 | |9;json-stringify-tinderbox;64;72;65;75;71;70;68;69;68;68 05:34:05 INFO - PROCESS | 7191 | |10;stanford-crypto-aes;82;83;84;82;83;83;82;96;111;109 05:34:05 INFO - PROCESS | 7191 | |11;stanford-crypto-ccm;124;131;127;124;116;119;126;136;118;126 05:34:05 INFO - PROCESS | 7191 | |12;stanford-crypto-pbkdf2;140;115;141;138;143;171;169;127;166;178 05:34:05 INFO - PROCESS | 7191 | |13;stanford-crypto-sha256-iterative;67;67;64;67;68;67;66;68;67;66 05:34:05 INFO - PROCESS | 7191 | __end_tp_report 05:34:05 INFO - PROCESS | 7191 | __start_cc_report 05:34:05 INFO - PROCESS | 7191 | _x_x_mozilla_cycle_collect,1198 05:34:05 INFO - PROCESS | 7191 | __end_cc_report 05:34:05 INFO - PROCESS | 7191 | __startTimestamp1455024845639__endTimestamp 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | ------- Summary: start ------- 05:34:05 INFO - PROCESS | 7191 | Number of tests: 14 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#0] ai-astar Cycles:10 Average:121.90 Median:112.50 stddev:20.87 (18.6%) stddev-sans-first:21.76 05:34:05 INFO - PROCESS | 7191 | Values: 111.0 109.0 113.0 111.0 112.0 110.0 120.0 158.0 164.0 111.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:159.00 stddev:21.47 (13.5%) stddev-sans-first:22.62 05:34:05 INFO - PROCESS | 7191 | Values: 155.0 119.0 165.0 156.0 141.0 174.0 167.0 120.0 162.0 119.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:2.95 (1.6%) stddev-sans-first:3.12 05:34:05 INFO - PROCESS | 7191 | Values: 185.0 185.0 184.0 190.0 186.0 188.0 185.0 180.0 182.0 182.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#3] audio-fft Cycles:10 Average:70.60 Median:77.00 stddev:11.14 (14.5%) stddev-sans-first:11.74 05:34:05 INFO - PROCESS | 7191 | Values: 74.0 77.0 86.0 78.0 60.0 60.0 57.0 81.0 77.0 56.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.50 stddev:19.07 (24.0%) stddev-sans-first:20.23 05:34:05 INFO - PROCESS | 7191 | Values: 93.0 97.0 132.0 79.0 80.0 80.0 113.0 75.0 79.0 110.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:86.50 stddev:18.85 (21.8%) stddev-sans-first:14.68 05:34:05 INFO - PROCESS | 7191 | Values: 132.0 130.0 84.0 90.0 84.0 89.0 84.0 90.0 84.0 89.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:94.00 stddev:16.14 (17.2%) stddev-sans-first:2.83 05:34:05 INFO - PROCESS | 7191 | Values: 145.0 102.0 95.0 93.0 94.0 93.0 94.0 93.0 94.0 94.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:84.00 stddev:5.50 (6.5%) stddev-sans-first:1.22 05:34:05 INFO - PROCESS | 7191 | Values: 101.0 81.0 84.0 84.0 85.0 84.0 85.0 85.0 84.0 84.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:3.11 (5.6%) stddev-sans-first:3.27 05:34:05 INFO - PROCESS | 7191 | Values: 56.0 55.0 55.0 53.0 53.0 55.0 54.0 63.0 52.0 53.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:3.23 (4.7%) stddev-sans-first:2.88 05:34:05 INFO - PROCESS | 7191 | Values: 64.0 72.0 65.0 75.0 71.0 70.0 68.0 69.0 68.0 68.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:83.00 stddev:11.60 (14.0%) stddev-sans-first:11.98 05:34:05 INFO - PROCESS | 7191 | Values: 82.0 83.0 84.0 82.0 83.0 83.0 82.0 96.0 111.0 109.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:126.00 stddev:6.06 (4.8%) stddev-sans-first:6.42 05:34:05 INFO - PROCESS | 7191 | Values: 124.0 131.0 127.0 124.0 116.0 119.0 126.0 136.0 118.0 126.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.80 Median:154.50 stddev:20.96 (13.6%) stddev-sans-first:21.99 05:34:05 INFO - PROCESS | 7191 | Values: 140.0 115.0 141.0 138.0 143.0 171.0 169.0 127.0 166.0 178.0 05:34:05 INFO - PROCESS | 7191 | 05:34:05 INFO - PROCESS | 7191 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 05:34:05 INFO - PROCESS | 7191 | Values: 67.0 67.0 64.0 67.0 68.0 67.0 66.0 68.0 67.0 66.0 05:34:05 INFO - PROCESS | 7191 | -------- Summary: end -------- 05:34:05 INFO - PROCESS | 7191 | 05:34:06 INFO - TEST-INFO | 7191: exit 0 05:34:06 INFO - TEST-OK | kraken | took 56855ms 05:34:06 INFO - SUITE-END | took 1171s 05:34:06 INFO - Completed test suite (00:19:32) 05:34:06 INFO - Working with test: dromaeo_css 05:34:06 INFO - Generating results file: dromaeo_css 05:34:06 INFO - Working with test: kraken 05:34:06 INFO - Generating results file: kraken 05:34:06 INFO - javascript benchmark 05:34:06 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:34:07 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 05:34:07 INFO - process_Request line: dromaeo_css 7603.95 graph.html#tests=[[72,1,59]] 05:34:07 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:34:07 INFO - process_Request line: kraken 1453.40 graph.html#tests=[[232,1,59]] 05:34:07 INFO - RETURN: dromaeo_css: 7603.95 05:34:07 INFO - RETURN: kraken: 1453.40 05:34:07 INFO - javascript benchmark 05:34:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3393.2195595013145, 3423.6234014258443, 3443.603985541662, 3460.031191266446, 3488.9476657850123, 5602.1596760485845, 5645.096784514479, 5674.0921452567645, 5681.602287839853, 5711.114777209539, 4666.76666166692, 5728.856778580537, 5734.111212762028, 5742.167385729067, 5815.302163740346, 5071.746412679369, 5122.026814905165, 5134.974325128369, 5151.356080489939, 5160.974195129019, 4677.2750223715375, 5232.3459567554055, 5234.214867769827, 5259.710715910624, 5306.893862122755, 5063.139266324733, 5086.949130508703, 5177.818776342848, 5190.740462976836, 5198.0, 5163.122269214243, 5163.664361816507, 5166.870828229286, 5172.818951336685, 5193.065248255312, 4768.833090841838, 4781.756743246746, 5117.309163262949, 5137.588992880561, 5170.146925757245, 5470.699111548864, 5591.608587398884, 5607.663540187561, 5618.4662457066925, 5618.971905140448, 6132.600268413486, 6147.0779383092895, 6165.969170154165, 6170.0, 6176.84557886052, 6207.87584248317, 6234.532410069263, 6242.96878515609, 6273.435390814803, 6284.528660350492, 5775.3935836736955, 5778.075507918755, 5788.986927287708, 5789.421057894177, 5801.883962320772, 6169.629822210682, 6170.259568851767, 6214.223222097238, 6261.467775239064, 6289.088082228142, 6111.877762444727, 6143.754249830046, 6167.198264225712, 6189.442950134419, 6199.101130336076, 6266.279377871603, 6266.937330626726, 6284.088807123033, 6284.528660350401, 6285.057241413733, 6137.938620613826, 6149.754009839557, 6187.752489900444, 6197.25632924043, 6218.720157592919, 6174.598651087665, 6180.536459765535, 6204.81385558434, 6274.937250627527, 6290.433860952537, 4903.313536104948, 4911.189653707098, 4929.162042452792, 4933.4819843915875, 4948.70307781527, 4334.1981733379425, 4423.911521769611, 4427.092446048617, 4465.084657645175, 4470.731756094645, 4719.669623126349, 5596.0206963781175, 5687.40282270364, 5744.856378590569, 5772.884542309131, 6244.843878902973, 6264.718087685974, 6268.561200715997, 6277.058441233851, 6288.968555157195, 5606.60753747242, 5682.289713785777, 5690.4025077367105, 5710.3433105193435, 5731.312242530923, 5356.571474282048, 5396.298481197419, 5417.783288668409, 5426.9185962210595, 5431.945680543223, 6213.751449942042, 6257.624542527463, 6288.0, 6323.747050118036, 6352.587081839665, 6222.719977600928, 6262.812115636538, 6263.78076767311, 6265.530085243716, 6266.342034086355, 6337.144485494399, 6372.107904893412, 6372.394622510854, 6380.074889140956, 6394.936050639434, 6052.061930400795, 6114.723190878713, 6116.0, 6181.721822518088, 6189.35011823752, 5157.7679004444135, 6226.5641405101205, 6230.968845155745, 6237.407446292595, 6251.562390632794, 5938.851528711644, 6052.90920636182, 6131.724072416841, 6136.7852125175405, 6141.508679305734, 6010.42900924429, 6080.148779171008, 6124.594542075475, 6124.991279909774, 6133.426475724657, 5955.851103722442, 5961.4038596140035, 5969.104634304889, 5979.282486101695, 6001.219841420588, 5123.718195499134, 5982.1325907744, 6087.147799308189, 6110.572259941761, 6112.219960280447, 5817.301923769175, 5843.912341314799, 5855.180274761452, 5894.174815525744, 5896.469317761422], "unit": "score", "name": "dojo.html", "value": 5663.221163187133}, {"lowerIsBetter": false, "replicates": [417.73056378635783, 519.0475477498794, 550.6613432738867, 552.4475524475524, 665.7436886798582, 29634.851825740894, 37182.81408592957, 37552.0, 37654.24691506168, 37695.05762355943, 40082.0, 40478.392824107665, 40535.0, 40572.0, 40626.796866015706, 9307.162355388014, 9541.522923853814, 9545.14093731564, 9545.856812147824, 9555.713328600154, 9442.952785236064, 9543.856842147374, 9589.712308630717, 9604.0, 9741.951290243538, 9716.417014979088, 9780.168685661727, 9794.461304628243, 9799.559019844124, 9810.950945245264, 9661.516924153764, 9741.951290243574, 9749.951250243703, 9804.117629413317, 9886.0, 9563.665271715456, 9700.805983880353, 9712.320137590372, 9718.951405242999, 9725.221982241474, 39107.60892391068, 39537.0, 39553.80223098895, 39554.208915821524, 39641.0, 1879.7723039297118, 3199.3921154980712, 3307.4212012897888, 3327.5008748687646, 3384.4077286475012, 25929.74070259311, 26851.731482685118, 27199.45601087987, 27364.58953115705, 27415.862920685267, 2729.972700272992, 2756.0629386008854, 2816.3944751878444, 2846.8291902485917, 2847.7437030667134, 335885.0, 336759.0, 459651.0, 460338.0, 505142.0, 50293.74853125711, 50329.49670503321, 50974.49025509771, 50976.74511627418, 50978.23532647013, 53014.204786928225, 53030.469695302556, 53155.468445315055, 53267.0, 53315.73342133342, 15839.604009899846, 16013.919930400274, 16019.919900400424, 16024.0, 16027.439039633557, 12462.501499940083, 12483.875161248452, 12513.74972500545, 12559.811602825965, 12577.685557861127, 19494.415167544998, 19755.901220493804, 19776.802231977785, 19786.802131978497, 19798.70301945472, 7398.741044063432, 7934.761957141295, 8460.576971151419, 8471.449355791854, 8499.447535910145, 983.5721461164417, 1045.7333379988054, 1130.6947124276564, 1139.5441823270592, 1190.357207108152, 29220.123396298146, 29591.408171836872, 29699.554506682416, 30279.394412112077, 30429.391412171633, 1031.6492392586406, 1066.9573217071386, 1101.146611376174, 1104.5305745058317, 1127.2785417332914, 15046.924765376103, 15106.924465377602, 15218.239088045775, 15593.688126237412, 15629.374825006873, 658092.0, 658607.0, 658757.0, 664804.0, 665294.0, 57247.71376143176, 57953.710231448575, 58009.709951450815, 58103.12845307324, 58116.12825807616, 1406.3038795796147, 1422.295963498075, 1444.6460617148866, 1445.4435042508499, 1450.5068276785944, 29136.7086329134, 29240.853795730884, 29244.0, 29968.85015574908, 29976.850115749283, 50221.0, 50623.0, 50628.49371506238, 50720.0, 50814.74592627013, 480336.0, 484299.0, 485235.0, 485533.0, 486437.0, 34854.82572587121, 35434.468482973294, 41238.38142427806, 41672.58327416687, 42079.57920420756, 41058.5629502966, 41489.58510414978, 41792.0, 42150.0, 42195.94510137271, 29330.413391732473, 34089.659103408645, 34434.48348274728, 35392.93821185369, 35471.64528354684, 34357.3405963127, 34841.07701690769, 35105.82447087748, 35159.12102197466, 35223.0, 33814.830925845214, 34626.0, 34956.82521587376, 36424.63575364213, 36440.81779591085, 29865.402691945605, 31505.52741708922, 31575.368492630478, 31586.210344741565, 31938.36123277568, 24472.632910506, 24527.509449810546, 24834.751652483243, 25039.499210016063, 25070.74929250757], "unit": "score", "name": "ext.html", "value": 19441.651568176254}, {"lowerIsBetter": false, "replicates": [313.7709472085379, 333.5630324275199, 343.6975461593798, 364.87594217965886, 368.4418106568547, 14643.853561464382, 14861.55415337539, 18657.813421865776, 18697.813021869777, 19877.105530251156, 11723.648290551277, 12103.818442723345, 15227.16250606219, 18587.814121858777, 18645.813541864576, 5252.343457067866, 5378.542823859977, 5380.166074258476, 5384.730763461831, 5393.78424863005, 3935.0, 4098.385242213662, 5117.8464646060675, 5220.190870415092, 7154.892676609855, 7192.604406757626, 7291.52605080668, 7472.701091956315, 7479.551226926402, 7479.962600187018, 7310.049693539806, 7367.1527774306105, 7372.557646541225, 7382.704691812348, 7385.670579295752, 6855.725770969155, 7052.823679408005, 7066.92933070673, 7067.717291308341, 7068.611226382547, 26166.73833261662, 26187.73812261872, 26267.080652177083, 27107.780149893104, 27617.03340383077, 2323.233333000123, 2388.4267775733874, 2604.7004594471687, 2798.5802129680505, 3223.468127758917, 15504.844951550453, 15589.844101559067, 15612.76580851288, 15641.608959775982, 16163.272652730655, 1903.1245627011592, 1939.8060193980489, 1959.1869374209687, 2042.428120126367, 2111.799379058987, 68340.31659683389, 92380.0, 97615.0, 98008.0, 99558.50220748922, 18406.723899141525, 19167.424977250706, 19255.51861203481, 19397.0, 19451.0, 25973.090941817325, 27456.90172393122, 28019.0, 28033.859830701123, 28075.43849123006, 8661.220490155916, 10614.0, 10625.5218515167, 10693.358398496115, 10728.89271107279, 9054.139856713733, 9072.410293330913, 9144.0, 9203.585838637277, 9210.355275130676, 7070.566097665431, 8276.758486227114, 8482.533460659599, 8541.743747687578, 8662.0, 3280.557124788123, 3410.5736782902136, 3412.129906873731, 3462.7576069674883, 3463.5324231228965, 863.5811631358786, 917.554985831871, 933.7898972731081, 993.6671215143008, 1054.9050585447346, 15298.770518442232, 15322.463713769965, 17961.1019449027, 18230.63538729218, 19896.60206795856, 850.2985037344092, 978.6232300564334, 985.7683444390562, 1003.2776400991274, 1020.1023099672241, 10160.33957792741, 10509.316894401993, 10581.523831427605, 10626.516952489068, 10692.251542392114, 650793.0, 688416.0, 690693.0, 734475.0, 742598.0, 104572.0, 105182.79268829314, 110178.0, 110261.0, 123672.0, 1056.6844278872852, 1138.8861113888545, 1200.8679045305184, 1237.3194742891374, 1285.3252042677668, 17517.737233941756, 18041.278348865897, 18067.91592504454, 18195.90824550531, 18350.72473912919, 50699.746501267255, 51197.0, 51687.449376518765, 51954.0, 52760.0, 67906.32093679, 69090.0, 69306.0, 70009.29990700028, 70966.0, 7356.742514011984, 7675.0, 7759.418043646636, 7763.92236077632, 7862.685492580233, 9763.609455621696, 11896.286222826659, 12190.329531875832, 12191.146619736533, 12617.116801824151, 10258.640947566799, 10474.738131546772, 10483.633072842415, 10663.253572250178, 10816.296940698829, 10100.343477673927, 10427.322224055411, 10558.155347572329, 10590.576376944837, 10610.045095941403, 10591.311564748266, 10642.680719578426, 10645.0951669109, 10759.89240107589, 10851.0, 6273.404026617464, 7609.6575654094595, 7626.8855967159425, 7637.159912409742, 7653.425993050655, 9058.139476749699, 10422.791544169226, 10487.793903701173, 10629.415382154055, 10648.148148148284], "unit": "score", "name": "jquery.html", "value": 10215.295878555247}, {"lowerIsBetter": false, "replicates": [1370.390176371515, 1494.551634509647, 1535.784990101386, 1750.8249175082494, 1922.4424916774128, 8011.158828323022, 8682.871226740523, 8831.072737362581, 8841.204291613745, 8846.248068914134, 8412.284955778767, 8720.956395218014, 8740.956295218532, 8742.0, 8742.344324175696, 2148.581026699794, 2212.590670725921, 2215.058600094961, 2461.667674863893, 2489.3776555861036, 2138.4119367173994, 2150.354893531942, 2153.06341741343, 2434.087217293515, 2497.8001759859167, 2411.247872719365, 2443.1815341860497, 2447.0945750072497, 2449.644801503781, 2453.58289090855, 2440.6217036359217, 2455.5579995600783, 2464.3346296499935, 2467.716212635534, 2467.987660061688, 2068.8758674479577, 2389.3787615219994, 2404.386881345263, 2427.1626288930293, 2428.7449817769234, 8897.243734282594, 8988.59551320192, 8994.910050899472, 8999.910000899972, 9007.909920900773, 5367.543758780508, 5511.917321240185, 5543.473370029841, 5546.69493177875, 5551.944480555183, 3614.204874927512, 3632.5640923089136, 3783.6027217142355, 3794.8102594870147, 3798.1833905710128, 1019.4902548725637, 1023.8668973033532, 1044.8276034454307, 1068.6793961811425, 1083.8049151152868, 12294.631161065183, 12296.692582685415, 12315.56895508662, 12322.445489952885, 12326.383680815923, 4897.681650692693, 4929.901401971941, 4959.181735013754, 4983.700977941263, 5026.447090820006, 10335.844962325571, 10340.482975851177, 10347.0, 10347.793044139074, 10357.4303413313, 5524.171374293807, 5528.917066244009, 5530.5299049580435, 5535.58483113768, 5543.36251331094, 5009.5992320615, 5017.473165317662, 5019.121653710659, 5022.0, 5026.447090820006, 3660.213054193361, 3910.7066969977363, 3943.566207717206, 3946.427767973628, 3997.380406037069, 1202.0, 1218.6100447856777, 1219.945102470391, 1221.407617305606, 1270.060155484953, 1288.1240756285815, 1291.231717128307, 1332.7201287729492, 1333.7732585460494, 1335.7729186038396, 7991.4006449515355, 8209.179082091743, 8350.749477515685, 8356.331493480508, 8384.413091083688, 1152.6772503698978, 1169.6958790714305, 1172.7185475485821, 1187.75057237981, 1191.7854786138405, 6320.905186422207, 6352.269489008823, 6404.103425520431, 6406.0, 6408.391202835724, 10439.843402348972, 10798.892011079946, 10813.459327033774, 10928.672139835817, 10950.835737463944, 8935.329850261127, 10287.279890407599, 10867.78264434707, 10933.125349971982, 11027.944860275647, 8137.267645911897, 8346.582670866432, 8358.414910956297, 8361.993121650881, 8382.622781974826, 7986.600669966595, 8122.675092996214, 8171.918280817354, 8185.017797864294, 8214.63034163476, 9403.4828084456, 9501.619935202514, 9644.614215431304, 9660.75848103803, 9686.70939871805, 9710.805783884423, 9936.354136981074, 10171.23715721309, 10278.794424111626, 10298.845517317395, 7726.845463090594, 7745.457817952689, 7798.103218129988, 7808.726694565663, 7864.449488535747, 8348.6243119061, 8442.704505342284, 8446.408751387344, 8459.915400845914, 8461.703840365557, 6795.592264464148, 7109.395701365442, 7201.531900426456, 7826.882596760939, 7981.920180798118, 7256.383207427428, 7623.352015078781, 7713.2286771322415, 7738.690452381842, 7793.06483222017, 7449.292317229854, 7745.612719364122, 7750.10873749526, 7771.455998080306, 7803.609819509115, 7076.787696369117, 7125.465590080661, 7126.251743567058, 7126.786196414116, 7136.5004449690105, 7113.466490013167, 7201.71193152268, 7205.747798827016, 7225.891611625935, 7226.674799633924], "unit": "score", "name": "mootools.html", "value": 4980.089567871562}, {"lowerIsBetter": false, "replicates": [321.55946353495716, 328.829008915364, 332.92509185433295, 333.3349966816202, 336.92756057447644, 5182.974085129578, 6434.3565643435695, 6439.259485159208, 6464.385883341075, 6520.7391704331885, 6067.726952287147, 6080.452759251666, 6239.282482514513, 6274.341194174603, 6433.099366088752, 4735.881602959919, 4742.241241401376, 4789.616830653539, 4797.472278049412, 4805.735684537349, 4577.9542204577865, 4787.9521204788025, 4804.855854324358, 4808.63935204861, 4816.54242846929, 4631.212693842038, 4660.580547750702, 4729.432468103832, 4746.430428348603, 4754.429468463772, 4740.241561350184, 4746.4541577718655, 4747.287906813971, 4756.357891684647, 4768.904621907543, 4098.754074755494, 4102.897427564305, 4102.979485102555, 4105.3020986432375, 4110.403991421257, 5961.821145365646, 6052.576319657625, 6139.079138129272, 6170.8765824683705, 6190.690465476753, 5608.2428872102155, 5613.494785469327, 5619.859503512404, 5622.49397554218, 5630.042892708249, 6854.383105520527, 6867.07294515239, 6867.965660171667, 6873.862522749517, 6874.931250687529, 4598.379218805452, 4598.609118224921, 4599.839005634787, 4600.562946520109, 4607.170709272363, 6885.690143943534, 7250.311220434103, 7276.781696549112, 7282.890756638654, 7290.59901705406, 4696.459907110658, 4705.223638099744, 4709.552592503706, 4712.858614241578, 4718.0, 4761.4524329701835, 4776.785044672998, 4784.808607655655, 4787.569118779326, 4800.591949684316, 5041.395032596039, 5144.948550514447, 5590.301212348457, 5590.91613625796, 5595.356533998643, 6837.863242735217, 6848.0, 6848.417884479775, 6851.314868513208, 6858.142732158481, 1643.6137507685685, 1673.3306677329165, 1675.0284834795789, 1675.7570152328087, 1677.1446562253095, 1083.550326614462, 1087.6138970665459, 1107.479484642217, 1108.578740078781, 1118.6532175025768, 6335.049742538656, 6340.873182536324, 6349.46029587481, 6367.840803979938, 6373.681315934185, 6837.726490940307, 7056.470764692566, 7092.4680648951535, 7096.361327480551, 7105.893411598727, 6273.9372606273355, 6418.743250269937, 6445.613263204129, 6450.935595626763, 6495.480361571157, 3725.2735716535017, 3760.9435858462143, 3763.0, 3767.6609105180664, 3768.660820526111, 7310.7075716971785, 7491.138519070268, 7502.2122677119205, 7529.8117547060665, 7543.245675432413, 7358.227386124487, 7370.152432470228, 7423.740169094056, 7450.994115794407, 7456.1798202197715, 7493.100827900686, 7505.66224519887, 7515.286047825448, 7516.774496765106, 7532.0, 6904.827379315558, 7104.325089116525, 7105.857882842418, 7110.537815042006, 7125.073740413713, 7016.052832867497, 7017.228104908354, 7029.191642961125, 7046.823829404306, 7123.643817809192, 7264.091988501437, 7545.849083018418, 7585.596664617064, 7591.962040189764, 7595.126560445398, 4528.705634133903, 4580.152671755736, 4585.403897493305, 4588.609968152745, 4592.242280023758, 6586.176727909012, 7172.964135179291, 7180.640967951686, 7194.568325900463, 7196.352328290479, 6688.565243259172, 6957.547759395826, 6961.965190174017, 6962.060121883481, 6975.128108986377, 5941.8116376723965, 6712.563683360566, 6891.483138764513, 6919.34266244726, 6926.515143939876, 6765.627890466071, 6767.289434609295, 6770.898436523357, 6771.356721111487, 6772.93227067723, 5612.382637909908, 5727.828165155052, 6411.294757576756, 6922.411595014481, 6942.444604431533, 6492.967535162294, 6715.3620406061345, 6761.763338283136, 6764.0, 6789.219239787488], "unit": "score", "name": "prototype.html", "value": 5063.318796084044}, {"lowerIsBetter": false, "replicates": [678.1421501800221, 679.962602056887, 710.8329542557498, 728.0644371982004, 737.1890919988017, 14495.130292182463, 14648.633784155401, 14663.706725865502, 14671.339789709462, 14680.632984175387, 15252.38990440381, 15615.687686246269, 15622.687546249068, 15647.687046259069, 15954.441594544192, 678.1556961582836, 691.332863786446, 839.1985653700701, 856.2507805670039, 856.9014563325235, 830.1366578758115, 833.7582101190648, 847.6100993542977, 848.6350869126273, 849.3332733803965, 895.9417637853551, 897.6544030548221, 899.9280057595377, 900.729781065681, 900.8153328567661, 707.1584814071239, 725.5646612032791, 867.4058270085012, 884.8893888263967, 890.5502721125822, 615.5075939248583, 630.6972653126479, 657.3623585122424, 658.5686375424104, 659.986800263992, 14672.853271467255, 14826.962112652118, 14838.109713417121, 14850.03474774147, 14874.033187842755, 12296.631101066982, 12343.567975120917, 12365.010799136138, 12404.13171078025, 12676.556320528738, 10113.949430252802, 10120.829396847541, 10149.191216067995, 10155.949220253851, 10222.0, 3418.5555877735737, 3448.6378930212218, 3455.2053027803745, 3457.913552161191, 3467.653234676512, 79861.0, 80352.0, 82957.0, 83030.58484707538, 83365.99999999939, 9202.217811486098, 9279.39683920543, 9281.257499400032, 9467.100625440573, 9508.524573771421, 20330.796692033186, 20346.28787992413, 20442.08010639525, 20444.488887777923, 20449.48876278105, 7406.7777966660005, 7414.0, 7459.776206713807, 7609.733659321897, 7701.691932322644, 4116.608922152391, 4165.187788381297, 4169.478815148106, 4171.97914010434, 4189.937150942738, 3544.80503572306, 3580.1407662160896, 3586.9282614347567, 3592.137886907176, 3598.1004748812798, 633.4995353670548, 642.7300533775825, 653.1052458132389, 659.1069101367674, 673.9755571531246, 523.5680563535099, 587.2776484923568, 596.9611975221596, 617.3425302053299, 623.3236937922314, 8041.678332866737, 8698.869516957251, 8785.51679657625, 8916.420432671855, 9084.364094513452, 579.4413028952106, 603.0983679399326, 609.326694003129, 618.1994317359031, 619.1734035063241, 3723.236736469017, 3764.322421964018, 3899.395593682984, 3964.5044369453817, 4046.5144182697677, 83328.26330881436, 83750.58124709337, 85007.57496212603, 85146.0, 85636.57181714052, 75166.0, 75202.11994700067, 75560.0, 75638.0, 76286.0, 67434.65130697457, 67493.32506674969, 69215.0, 69298.65350673316, 69712.6514367425, 6548.443382312556, 8229.629666665134, 8536.871946920925, 8664.436811607226, 8891.244244239311, 48657.75671121763, 48759.75620121877, 49025.5097449021, 50190.0, 50521.24218136657, 64676.0, 66664.0, 66742.0, 68384.0, 69315.65342173257, 11181.832272516082, 11381.829272560753, 11642.41787910618, 11642.825357619473, 11718.296902185555, 11746.353950532803, 12112.333821639895, 12370.591941108281, 12656.0, 12760.0, 11037.558497660004, 11487.827682584935, 11530.0, 11765.524784965017, 12355.32045737493, 11248.662540123809, 11856.11079169083, 11931.985781208694, 11963.76072478563, 12325.876741232472, 10468.267221294436, 11472.713182170513, 11525.252375076436, 11721.706957326134, 11845.407729613657, 9215.539223038688, 9326.580303886478, 9359.85960210611, 9453.33826632129, 9929.453880036668, 7453.850922981402, 8970.282377409661, 9128.269738420777, 9144.862827057466, 9837.311388202757], "unit": "score", "name": "yui.html", "value": 6815.237531315045}], "name": "dromaeo_css", "value": 7603.953161574018}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 109.0, 113.0, 111.0, 112.0, 110.0, 120.0, 158.0, 164.0, 111.0], "unit": "score", "name": "ai-astar", "value": 121.9}, {"lowerIsBetter": true, "replicates": [155.0, 119.0, 165.0, 156.0, 141.0, 174.0, 167.0, 120.0, 162.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 147.8}, {"lowerIsBetter": true, "replicates": [185.0, 185.0, 184.0, 190.0, 186.0, 188.0, 185.0, 180.0, 182.0, 182.0], "unit": "score", "name": "audio-dft", "value": 184.7}, {"lowerIsBetter": true, "replicates": [74.0, 77.0, 86.0, 78.0, 60.0, 60.0, 57.0, 81.0, 77.0, 56.0], "unit": "score", "name": "audio-fft", "value": 70.6}, {"lowerIsBetter": true, "replicates": [93.0, 97.0, 132.0, 79.0, 80.0, 80.0, 113.0, 75.0, 79.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 93.8}, {"lowerIsBetter": true, "replicates": [132.0, 130.0, 84.0, 90.0, 84.0, 89.0, 84.0, 90.0, 84.0, 89.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 95.6}, {"lowerIsBetter": true, "replicates": [145.0, 102.0, 95.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 99.7}, {"lowerIsBetter": true, "replicates": [101.0, 81.0, 84.0, 84.0, 85.0, 84.0, 85.0, 85.0, 84.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 85.7}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 53.0, 53.0, 55.0, 54.0, 63.0, 52.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 54.9}, {"lowerIsBetter": true, "replicates": [64.0, 72.0, 65.0, 75.0, 71.0, 70.0, 68.0, 69.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.0}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 84.0, 82.0, 83.0, 83.0, 82.0, 96.0, 111.0, 109.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.5}, {"lowerIsBetter": true, "replicates": [124.0, 131.0, 127.0, 124.0, 116.0, 119.0, 126.0, 136.0, 118.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.7}, {"lowerIsBetter": true, "replicates": [140.0, 115.0, 141.0, 138.0, 143.0, 171.0, 169.0, 127.0, 166.0, 178.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.8}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 64.0, 67.0, 68.0, 67.0, 66.0, 68.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1453.4}]} 05:34:07 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1453.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7603.95"}}} 05:34:07 INFO - Return code: 0 05:34:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:34:07 INFO - # TBPL SUCCESS # 05:34:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:07 INFO - 2016-02-09 05:34:07.382 screenresolution[7195:119877] starting screenresolution argv=screenresolution get 05:34:07 INFO - 2016-02-09 05:34:07.385 screenresolution[7195:119877] Display 0: 1600x1200x32@60 05:34:07 INFO - 2016-02-09 05:34:07.391 screenresolution[7196:119880] starting screenresolution argv=screenresolution list 05:34:07 INFO - Available Modes on Display 0 05:34:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:07 INFO - Intel Iris: 05:34:07 INFO - Chipset Model: Intel Iris 05:34:07 INFO - Type: GPU 05:34:07 INFO - Bus: Built-In 05:34:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:07 INFO - Vendor: Intel (0x8086) 05:34:07 INFO - Device ID: 0x0a2e 05:34:07 INFO - Revision ID: 0x0009 05:34:07 INFO - Displays: 05:34:07 INFO - Display: 05:34:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:07 INFO - Display Serial Number: 1600x1200 60 05:34:07 INFO - Main Display: Yes 05:34:07 INFO - Mirror: Off 05:34:07 INFO - Online: Yes 05:34:07 INFO - Rotation: Supported 05:34:07 INFO - Return code: 0 05:34:07 INFO - Running post-action listener: _resource_record_post_action 05:34:07 INFO - Running post-run listener: _resource_record_post_run 05:34:08 INFO - Total resource usage - Wall time: 1209s; CPU: 52.0%; Read bytes: 2293760; Write bytes: 709289472; Read time: 33; Write time: 2045 05:34:08 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401424896; Write bytes: 395912704; Read time: 31684; Write time: 714 05:34:08 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 2293760; Write bytes: 299790336; Read time: 33; Write time: 1288 05:34:08 INFO - Running post-run listener: _upload_blobber_files 05:34:08 INFO - Blob upload gear active. 05:34:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:34:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:34:09 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:34:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:34:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:09 INFO - (blobuploader) - INFO - Done attempting. 05:34:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:34:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:10 INFO - (blobuploader) - INFO - Done attempting. 05:34:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:10 INFO - Return code: 0 05:34:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13a0447b1f91b8bbcf4dd015f6f67002cecda4fb56f55f98ebae257b588d5e52f5469136840dd519ab13a5046ce70a9a51aa1981fca5b096d30fdbe250cf2793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/708714cc2dcbe76afaf936cc8252d0fa6d9a033a1efce74ff8f2368a2d3e30b612aa8243e0a2e1937233d6eedcb11d1109f72310ef9a7cb89d89754d4ba669a1"} 05:34:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:10 INFO - Contents: 05:34:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13a0447b1f91b8bbcf4dd015f6f67002cecda4fb56f55f98ebae257b588d5e52f5469136840dd519ab13a5046ce70a9a51aa1981fca5b096d30fdbe250cf2793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/708714cc2dcbe76afaf936cc8252d0fa6d9a033a1efce74ff8f2368a2d3e30b612aa8243e0a2e1937233d6eedcb11d1109f72310ef9a7cb89d89754d4ba669a1"} 05:34:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:10 INFO - Copying logs to upload dir... 05:34:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.165985 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-02-09 05:34:10.820553) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-09 05:34:10.825194) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13a0447b1f91b8bbcf4dd015f6f67002cecda4fb56f55f98ebae257b588d5e52f5469136840dd519ab13a5046ce70a9a51aa1981fca5b096d30fdbe250cf2793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/708714cc2dcbe76afaf936cc8252d0fa6d9a033a1efce74ff8f2368a2d3e30b612aa8243e0a2e1937233d6eedcb11d1109f72310ef9a7cb89d89754d4ba669a1"} build_url:https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011419 build_url: 'https://queue.taskcluster.net/v1/task/SPGLEBIFQOu-z4v4mdhDqg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13a0447b1f91b8bbcf4dd015f6f67002cecda4fb56f55f98ebae257b588d5e52f5469136840dd519ab13a5046ce70a9a51aa1981fca5b096d30fdbe250cf2793", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/708714cc2dcbe76afaf936cc8252d0fa6d9a033a1efce74ff8f2368a2d3e30b612aa8243e0a2e1937233d6eedcb11d1109f72310ef9a7cb89d89754d4ba669a1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-09 05:34:10.851545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:34:10.851844) ========= 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.wZNRUiSCPG/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.jyFvbPgkHE/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.005494 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 05:34:10.900451) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-09 05:34:10.900769) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-09 05:34:10.904179) ========= ========= Total master_lag: 0.34 =========