builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0023 starttime: 1457530070.17 results: success (0) buildid: 20160309030419 builduid: 836d8cdc2cbe4e6b8740460e8465cbfe revision: af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-09 05:27:50.172943) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-09 05:27:50.173405) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:50.173699) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005675 basedir: '/builds/slave/test' ========= master_lag: 1.14 ========= ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:51.316956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-03-09 05:27:51.317445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-03-09 05:27:53.546355) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:53.546777) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005363 ========= master_lag: 1.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:54.715859) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-09 05:27:54.716364) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-09 05:27:54.716922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-03-09 05:27:54.717375) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-03-09 05:27:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-03-09 05:27:57 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=2.855156 ========= master_lag: 0.59 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-03-09 05:27:58.161894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:58.162260) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.029155 ========= master_lag: 1.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-09 05:27:59.203750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-03-09 05:27:59.204164) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 --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 af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-03-09 05:27:59,510 truncating revision to first 12 chars 2016-03-09 05:27:59,511 Setting DEBUG logging. 2016-03-09 05:27:59,511 attempt 1/10 2016-03-09 05:27:59,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/af7c0cb0798f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-09 05:28:03,427 unpacking tar archive at: mozilla-central-af7c0cb0798f/testing/mozharness/ program finished with exit code 0 elapsedTime=4.162756 ========= master_lag: 0.53 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-03-09 05:28:03.895751) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 3 secs) (at 2016-03-09 05:28:03.896160) ========= echo af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 program finished with exit code 0 elapsedTime=0.005946 script_repo_revision: 'af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86' ========= master_lag: 3.18 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 3 secs) (at 2016-03-09 05:28:07.082698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-03-09 05:28:07.083073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-03-09 05:28:09.175882) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-03-09 05:28:09.176326) ========= /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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:28:09 INFO - MultiFileLogger online at 20160309 05:28:09 in /builds/slave/test 05:28:09 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:28:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:28:09 INFO - {'append_to_log': False, 05:28:09 INFO - 'base_work_dir': '/builds/slave/test', 05:28:09 INFO - 'blob_upload_branch': 'Firefox', 05:28:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:28:09 INFO - 'branch': 'Firefox', 05:28:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:09 INFO - 'config_files': ('talos/mac_config.py',), 05:28:09 INFO - 'default_actions': ('clobber', 05:28:09 INFO - 'read-buildbot-config', 05:28:09 INFO - 'download-and-extract', 05:28:09 INFO - 'populate-webroot', 05:28:09 INFO - 'create-virtualenv', 05:28:09 INFO - 'install', 05:28:09 INFO - 'run-tests'), 05:28:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:09 INFO - 'download_minidump_stackwalk': True, 05:28:09 INFO - 'download_symbols': 'ondemand', 05:28:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:09 INFO - 'installer_path': 'installer.exe', 05:28:09 INFO - 'log_level': 'info', 05:28:09 INFO - 'log_name': 'talos', 05:28:09 INFO - 'log_to_console': True, 05:28:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:28:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:28:09 INFO - 'opt_config_files': (), 05:28:09 INFO - 'pip_index': False, 05:28:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:09 INFO - 'cmd': ('bash', 05:28:09 INFO - '-c', 05:28:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:09 INFO - 'enabled': True, 05:28:09 INFO - 'halt_on_failure': False, 05:28:09 INFO - 'name': 'check_screen_resolution'},), 05:28:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:09 INFO - 'cmd': ('bash', 05:28:09 INFO - '-c', 05:28:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:09 INFO - 'enabled': True, 05:28:09 INFO - 'halt_on_failure': False, 05:28:09 INFO - 'name': 'check_screen_resolution'},), 05:28:09 INFO - 'run_cmd_checks_enabled': True, 05:28:09 INFO - 'sps_profile': False, 05:28:09 INFO - 'sps_profile_interval': 0, 05:28:09 INFO - 'suite': 'dromaeojs', 05:28:09 INFO - 'system_bits': '32', 05:28:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:09 INFO - 'title': 't-yosemite-r7-0023', 05:28:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:09 INFO - 'use_talos_json': True, 05:28:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:28:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:09 INFO - 'work_dir': 'build'} 05:28:09 INFO - ##### 05:28:09 INFO - ##### Running clobber step. 05:28:09 INFO - ##### 05:28:09 INFO - Running pre-action listener: _resource_record_pre_action 05:28:09 INFO - Running main action method: clobber 05:28:09 INFO - rmtree: /builds/slave/test/build 05:28:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:28:11 INFO - Running post-action listener: _resource_record_post_action 05:28:11 INFO - ##### 05:28:11 INFO - ##### Running read-buildbot-config step. 05:28:11 INFO - ##### 05:28:11 INFO - Running pre-action listener: _resource_record_pre_action 05:28:11 INFO - Running main action method: read_buildbot_config 05:28:11 INFO - Using buildbot properties: 05:28:11 INFO - { 05:28:11 INFO - "project": "", 05:28:11 INFO - "product": "firefox", 05:28:11 INFO - "script_repo_revision": "production", 05:28:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:28:11 INFO - "repository": "", 05:28:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:28:11 INFO - "buildid": "20160309030419", 05:28:11 INFO - "stage_platform": "macosx64", 05:28:11 INFO - "basedir": "/builds/slave/test", 05:28:11 INFO - "nightly_build": "True", 05:28:11 INFO - "buildnumber": 160, 05:28:11 INFO - "slavename": "t-yosemite-r7-0023", 05:28:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:28:11 INFO - "platform": "yosemite_r7", 05:28:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:28:11 INFO - "branch": "mozilla-central", 05:28:11 INFO - "repo_path": "mozilla-central", 05:28:11 INFO - "revision": "af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86", 05:28:11 INFO - "pgo_build": "False", 05:28:11 INFO - "builduid": "836d8cdc2cbe4e6b8740460e8465cbfe", 05:28:11 INFO - "slavebuilddir": "test" 05:28:11 INFO - } 05:28:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:28:11 INFO - Running post-action listener: _resource_record_post_action 05:28:11 INFO - ##### 05:28:11 INFO - ##### Running download-and-extract step. 05:28:11 INFO - ##### 05:28:11 INFO - Running pre-action listener: _resource_record_pre_action 05:28:11 INFO - Running main action method: download_and_extract 05:28:11 INFO - mkdir: /builds/slave/test/build/tests 05:28: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:28:11 INFO - https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:28:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:28:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:28:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:28:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:28:14 INFO - Downloaded 1199 bytes. 05:28:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:28:14 INFO - Using the following test package requirements: 05:28:14 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:28:14 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:28:14 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:28:14 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'jsshell-mac.zip'], 05:28:14 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:28:14 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:28:14 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:28:14 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:28:14 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:28:14 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:28:14 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:28:14 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:14 INFO - https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:28:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:28:17 INFO - Downloaded 17866104 bytes. 05:28:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:28:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:28:21 INFO - Return code: 0 05:28:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:21 INFO - https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:28:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:28:23 INFO - Downloaded 17866104 bytes. 05:28:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:28:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:28:27 INFO - Return code: 0 05:28:27 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:28:27 INFO - https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:28:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:28:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:28:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:28:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:28:28 INFO - Downloaded 10845161 bytes. 05:28:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:28:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:28:29 INFO - Return code: 0 05:28:29 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:28:29 INFO - https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:28:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:28:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:28:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:28:34 INFO - Downloaded 111057802 bytes. 05:28:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:28:34 INFO - mkdir: /builds/slave/test/properties 05:28:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:34 INFO - Contents: 05:28:34 INFO - build_url:https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:29:07 WARNING - Can't figure out symbols_url from installer_url: https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg! 05:29:07 INFO - Running post-action listener: _resource_record_post_action 05:29:07 INFO - Running post-action listener: set_extra_try_arguments 05:29:07 INFO - ##### 05:29:07 INFO - ##### Running populate-webroot step. 05:29:07 INFO - ##### 05:29:07 INFO - Running pre-action listener: _resource_record_pre_action 05:29:07 INFO - Running main action method: populate_webroot 05:29:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:07 INFO - '--tppagecycles', 05:29:07 INFO - '7'], 05:29:07 INFO - 'tests': ['tsvgm']}}, 05:29:07 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:29:07 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:07 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:29:07 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'tests': ['damp', 'tps']}, 05:29:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['damp', 'tps']}, 05:29:07 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:29:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:07 INFO - 'other': {'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'other_l64': {'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'other_nol64': {'tests': ['a11yr', 05:29:07 INFO - 'ts_paint', 05:29:07 INFO - 'tpaint', 05:29:07 INFO - 'sessionrestore', 05:29:07 INFO - 'sessionrestore_no_auto_restore', 05:29:07 INFO - 'tabpaint']}, 05:29:07 INFO - 'svgr': {'tests': ['tsvgx', 05:29:07 INFO - 'tsvgr_opacity', 05:29:07 INFO - 'tart', 05:29:07 INFO - 'tscrollx', 05:29:07 INFO - 'cart']}, 05:29:07 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['tsvgx', 05:29:07 INFO - 'tsvgr_opacity', 05:29:07 INFO - 'tart', 05:29:07 INFO - 'tscrollx', 05:29:07 INFO - 'cart']}, 05:29:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'tests': ['tp5o']}, 05:29:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'talos_options': ['--e10s'], 05:29:07 INFO - 'tests': ['tp5o']}, 05:29:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'talos_options': ['--xperf_path', 05:29:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:07 INFO - 'tests': ['tp5n']}, 05:29:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:07 INFO - 'talos_options': ['--e10s', 05:29:07 INFO - '--xperf_path', 05:29:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:07 INFO - 'tests': ['tp5n']}}, 05:29:07 INFO - 'talos.zip': {'path': '', 05:29:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:07 INFO - Running post-action listener: _resource_record_post_action 05:29:07 INFO - ##### 05:29:07 INFO - ##### Running create-virtualenv step. 05:29:07 INFO - ##### 05:29:07 INFO - Running pre-action listener: _resource_record_pre_action 05:29:07 INFO - Running main action method: create_virtualenv 05:29:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:07 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:29:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:07 INFO - Using real prefix '/tools/python27' 05:29:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:11 INFO - Installing pip.................done. 05:29:11 INFO - Return code: 0 05:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29: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:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:11 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:11 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:29:11 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:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:11 INFO - 'HOME': '/Users/cltbld', 05:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:11 INFO - 'LOGNAME': 'cltbld', 05:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:11 INFO - 'MOZ_NO_REMOTE': '1', 05:29:11 INFO - 'NO_EM_RESTART': '1', 05:29:11 INFO - 'PAGER': '/bin/cat', 05:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:11 INFO - 'PWD': '/builds/slave/test', 05:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:11 INFO - 'SHELL': '/bin/bash', 05:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:11 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:11 INFO - 'USER': 'cltbld', 05:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:11 INFO - 'XPC_FLAGS': '0x0', 05:29:11 INFO - 'XPC_SERVICE_NAME': '0', 05:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:11 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:29:11 INFO - Cleaning up... 05:29:11 INFO - Return code: 0 05:29:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:29: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:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:11 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:11 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:29:11 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:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:11 INFO - 'HOME': '/Users/cltbld', 05:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:11 INFO - 'LOGNAME': 'cltbld', 05:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:11 INFO - 'MOZ_NO_REMOTE': '1', 05:29:11 INFO - 'NO_EM_RESTART': '1', 05:29:11 INFO - 'PAGER': '/bin/cat', 05:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:11 INFO - 'PWD': '/builds/slave/test', 05:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:11 INFO - 'SHELL': '/bin/bash', 05:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:11 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:11 INFO - 'USER': 'cltbld', 05:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:11 INFO - 'XPC_FLAGS': '0x0', 05:29:11 INFO - 'XPC_SERVICE_NAME': '0', 05:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:11 INFO - Downloading/unpacking psutil>=0.7.1 05:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:16 INFO - Installing collected packages: psutil 05:29:16 INFO - Running setup.py install for psutil 05:29:16 INFO - building 'psutil._psutil_osx' extension 05:29:16 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:29:17 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:29:17 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:29:17 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:29:17 INFO - building 'psutil._psutil_posix' extension 05:29:17 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:29:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:29:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:29:17 INFO - ^ 05:29:17 INFO - 1 warning generated. 05:29:17 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:29:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:17 INFO - Successfully installed psutil 05:29:17 INFO - Cleaning up... 05:29:17 INFO - Return code: 0 05:29:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:29: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:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:17 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:17 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:29:17 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:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:17 INFO - 'HOME': '/Users/cltbld', 05:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:17 INFO - 'LOGNAME': 'cltbld', 05:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:17 INFO - 'MOZ_NO_REMOTE': '1', 05:29:17 INFO - 'NO_EM_RESTART': '1', 05:29:17 INFO - 'PAGER': '/bin/cat', 05:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:17 INFO - 'PWD': '/builds/slave/test', 05:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:17 INFO - 'SHELL': '/bin/bash', 05:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:17 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:17 INFO - 'USER': 'cltbld', 05:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:17 INFO - 'XPC_FLAGS': '0x0', 05:29:17 INFO - 'XPC_SERVICE_NAME': '0', 05:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:20 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:29:20 INFO - Installing collected packages: mozsystemmonitor 05:29:20 INFO - Running setup.py install for mozsystemmonitor 05:29:20 INFO - Successfully installed mozsystemmonitor 05:29:20 INFO - Cleaning up... 05:29:20 INFO - Return code: 0 05:29:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:29:20 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:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:20 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:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:20 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:20 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:29:20 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:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:20 INFO - 'HOME': '/Users/cltbld', 05:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:20 INFO - 'LOGNAME': 'cltbld', 05:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:20 INFO - 'MOZ_NO_REMOTE': '1', 05:29:20 INFO - 'NO_EM_RESTART': '1', 05:29:20 INFO - 'PAGER': '/bin/cat', 05:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:20 INFO - 'PWD': '/builds/slave/test', 05:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:20 INFO - 'SHELL': '/bin/bash', 05:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:20 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:20 INFO - 'USER': 'cltbld', 05:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:20 INFO - 'XPC_FLAGS': '0x0', 05:29:20 INFO - 'XPC_SERVICE_NAME': '0', 05:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:21 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:23 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:23 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:29:23 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:29:23 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:29:23 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:29:23 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:29:23 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:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:29:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:24 INFO - Downloading docopt-0.6.1.tar.gz 05:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:24 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:24 INFO - Running setup.py install for blobuploader 05:29:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:29:24 INFO - Running setup.py install for requests 05:29:25 INFO - Running setup.py install for docopt 05:29:25 INFO - Successfully installed blobuploader requests docopt 05:29:25 INFO - Cleaning up... 05:29:25 INFO - Return code: 0 05:29:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:25 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:25 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:29:25 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:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:25 INFO - 'HOME': '/Users/cltbld', 05:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:25 INFO - 'LOGNAME': 'cltbld', 05:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:25 INFO - 'MOZ_NO_REMOTE': '1', 05:29:25 INFO - 'NO_EM_RESTART': '1', 05:29:25 INFO - 'PAGER': '/bin/cat', 05:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:25 INFO - 'PWD': '/builds/slave/test', 05:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:25 INFO - 'SHELL': '/bin/bash', 05:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:25 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:25 INFO - 'USER': 'cltbld', 05:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:25 INFO - 'XPC_FLAGS': '0x0', 05:29:25 INFO - 'XPC_SERVICE_NAME': '0', 05:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9teKl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9iXoHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5FaTA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hNEbpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-lhiFzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HfT09f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-f4yr9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-VRHJ7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-n4t1h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-2k4kyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ZDPzsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-32ljnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-R9jhwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-s8awIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-MOSVea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-OZhoQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:27 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-x9quPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:27 INFO - Running setup.py install for manifestparser 05:29:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:29:27 INFO - Running setup.py install for mozcrash 05:29:27 INFO - Running setup.py install for mozdebug 05:29:28 INFO - Running setup.py install for mozdevice 05:29:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Running setup.py install for mozfile 05:29:28 INFO - Running setup.py install for mozhttpd 05:29:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Running setup.py install for mozinfo 05:29:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Running setup.py install for mozInstall 05:29:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:29:28 INFO - Running setup.py install for mozleak 05:29:28 INFO - Running setup.py install for mozlog 05:29:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Running setup.py install for moznetwork 05:29:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Running setup.py install for mozprocess 05:29:29 INFO - Running setup.py install for mozprofile 05:29:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Running setup.py install for mozrunner 05:29:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:29:29 INFO - Running setup.py install for mozscreenshot 05:29:29 INFO - Running setup.py install for moztest 05:29:30 INFO - Running setup.py install for mozversion 05:29:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:29:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:30 INFO - Cleaning up... 05:29:30 INFO - Return code: 0 05:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:30 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:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:30 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:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:30 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:30 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:29:30 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:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:30 INFO - 'HOME': '/Users/cltbld', 05:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:30 INFO - 'LOGNAME': 'cltbld', 05:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:30 INFO - 'MOZ_NO_REMOTE': '1', 05:29:30 INFO - 'NO_EM_RESTART': '1', 05:29:30 INFO - 'PAGER': '/bin/cat', 05:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:30 INFO - 'PWD': '/builds/slave/test', 05:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:30 INFO - 'SHELL': '/bin/bash', 05:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:30 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:30 INFO - 'USER': 'cltbld', 05:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:30 INFO - 'XPC_FLAGS': '0x0', 05:29:30 INFO - 'XPC_SERVICE_NAME': '0', 05:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nFtb08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:30 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:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ThHXjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-F74ayF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:30 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:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-K2AwTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:30 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:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-aalVfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hNfV6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-fvKsVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nN6zyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-k1wpV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HWwG8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5TjFtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Wt70VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-L8tg8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:31 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:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:31 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tyg9Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:32 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:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:32 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-uJ3Ake-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:32 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:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:32 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-v_xfv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:32 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:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:32 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mcNRhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:32 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:29:32 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:32 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:32 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:29:32 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:29:32 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:29:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:29: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:29: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:29: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:29: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:29: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:29: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:29:34 INFO - Downloading blessings-1.6.tar.gz 05:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:34 INFO - Installing collected packages: blessings 05:29:34 INFO - Running setup.py install for blessings 05:29:34 INFO - Successfully installed blessings 05:29:34 INFO - Cleaning up... 05:29:35 INFO - Return code: 0 05:29:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:29:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:35 INFO - Reading from file tmpfile_stdout 05:29:35 INFO - Current package versions: 05:29:35 INFO - blessings == 1.6 05:29:35 INFO - blobuploader == 1.2.4 05:29:35 INFO - docopt == 0.6.1 05:29:35 INFO - manifestparser == 1.1 05:29:35 INFO - mozInstall == 1.12 05:29:35 INFO - mozcrash == 0.17 05:29:35 INFO - mozdebug == 0.1 05:29:35 INFO - mozdevice == 0.48 05:29:35 INFO - mozfile == 1.2 05:29:35 INFO - mozhttpd == 0.7 05:29:35 INFO - mozinfo == 0.9 05:29:35 INFO - mozleak == 0.1 05:29:35 INFO - mozlog == 3.1 05:29:35 INFO - moznetwork == 0.27 05:29:35 INFO - mozprocess == 0.22 05:29:35 INFO - mozprofile == 0.28 05:29:35 INFO - mozrunner == 6.11 05:29:35 INFO - mozscreenshot == 0.1 05:29:35 INFO - mozsystemmonitor == 0.0 05:29:35 INFO - moztest == 0.7 05:29:35 INFO - mozversion == 1.4 05:29:35 INFO - psutil == 3.1.1 05:29:35 INFO - requests == 1.2.3 05:29:35 INFO - wsgiref == 0.1.2 05:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29: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:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:35 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 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:35 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:29:35 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:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:35 INFO - 'HOME': '/Users/cltbld', 05:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:35 INFO - 'LOGNAME': 'cltbld', 05:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:35 INFO - 'MOZ_NO_REMOTE': '1', 05:29:35 INFO - 'NO_EM_RESTART': '1', 05:29:35 INFO - 'PAGER': '/bin/cat', 05:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:35 INFO - 'PWD': '/builds/slave/test', 05:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:35 INFO - 'SHELL': '/bin/bash', 05:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:35 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:35 INFO - 'USER': 'cltbld', 05:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:35 INFO - 'XPC_FLAGS': '0x0', 05:29:35 INFO - 'XPC_SERVICE_NAME': '0', 05:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 INFO - Cleaning up... 05:29:35 INFO - Return code: 0 05:29:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:29: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:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29: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', 'jsonschema'] in /builds/slave/test/build 05:29: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 jsonschema 05:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:35 INFO - 'HOME': '/Users/cltbld', 05:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:35 INFO - 'LOGNAME': 'cltbld', 05:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:35 INFO - 'MOZ_NO_REMOTE': '1', 05:29:35 INFO - 'NO_EM_RESTART': '1', 05:29:35 INFO - 'PAGER': '/bin/cat', 05:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:35 INFO - 'PWD': '/builds/slave/test', 05:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:35 INFO - 'SHELL': '/bin/bash', 05:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:35 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:35 INFO - 'USER': 'cltbld', 05:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:35 INFO - 'XPC_FLAGS': '0x0', 05:29:35 INFO - 'XPC_SERVICE_NAME': '0', 05:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:35 INFO - Downloading/unpacking jsonschema 05:29: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:29: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:29: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:29: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:29: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:29: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:29:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:38 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29: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:29: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:29: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:29: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:29: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:29: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:29:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:38 INFO - warning: no files found matching '*.txt' 05:29:38 INFO - no previously-included directories found matching 'build' 05:29:38 INFO - no previously-included directories found matching 'dist' 05:29:38 INFO - no previously-included directories found matching '.git*' 05:29:38 INFO - Installing collected packages: jsonschema, functools32 05:29:38 INFO - Running setup.py install for functools32 05:29:38 INFO - warning: no files found matching '*.txt' 05:29:38 INFO - no previously-included directories found matching 'build' 05:29:38 INFO - no previously-included directories found matching 'dist' 05:29:38 INFO - no previously-included directories found matching '.git*' 05:29:38 INFO - Successfully installed jsonschema functools32 05:29:38 INFO - Cleaning up... 05:29:39 INFO - Return code: 0 05:29:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:29: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:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10073aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007424c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe753c73b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, '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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:29:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:29:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:39 INFO - 'HOME': '/Users/cltbld', 05:29:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:39 INFO - 'LOGNAME': 'cltbld', 05:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:39 INFO - 'MOZ_NO_REMOTE': '1', 05:29:39 INFO - 'NO_EM_RESTART': '1', 05:29:39 INFO - 'PAGER': '/bin/cat', 05:29:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:39 INFO - 'PWD': '/builds/slave/test', 05:29:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:39 INFO - 'SHELL': '/bin/bash', 05:29:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:29:39 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:29:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:39 INFO - 'USER': 'cltbld', 05:29:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:39 INFO - 'XPC_FLAGS': '0x0', 05:29:39 INFO - 'XPC_SERVICE_NAME': '0', 05:29:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:39 INFO - Downloading/unpacking flake8 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:29:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:29:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:29:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:42 INFO - no previously-included directories found matching 'docs/_build' 05:29:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:29:42 INFO - Running setup.py install for pep8 05:29:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:29:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:29:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:29:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:29:43 INFO - no previously-included directories found matching 'docs/_build' 05:29:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:29:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:29:43 INFO - Cleaning up... 05:29:43 INFO - Return code: 0 05:29:43 INFO - Running post-action listener: _resource_record_post_action 05:29:43 INFO - Running post-action listener: _start_resource_monitoring 05:29:43 INFO - Starting resource monitoring. 05:29:43 INFO - ##### 05:29:43 INFO - ##### Running install step. 05:29:43 INFO - ##### 05:29:43 INFO - Running pre-action listener: _resource_record_pre_action 05:29:43 INFO - Running main action method: install 05:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:43 INFO - Reading from file tmpfile_stdout 05:29:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:29:43 INFO - Reading from file tmpfile_stdout 05:29:43 INFO - Output received: 05:29:43 INFO - Usage: mozinstall [options] installer 05:29:43 INFO - Options: 05:29:43 INFO - -h, --help show this help message and exit 05:29:43 INFO - -d DEST, --destination=DEST 05:29:43 INFO - Directory to install application into. [default: 05:29:43 INFO - "/builds/slave/test"] 05:29:43 INFO - --app=APP Application being installed. [default: firefox] 05:29:43 INFO - mkdir: /builds/slave/test/build/application 05:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:30:17 INFO - Reading from file tmpfile_stdout 05:30:17 INFO - Output received: 05:30:17 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:30:17 INFO - Running post-action listener: _resource_record_post_action 05:30:17 INFO - ##### 05:30:17 INFO - ##### Running run-tests step. 05:30:17 INFO - ##### 05:30:17 INFO - Running pre-action listener: _resource_record_pre_action 05:30:17 INFO - Running main action method: run_tests 05:30:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:17 INFO - 2016-03-09 05:30:17.199 screenresolution[1645:7675] starting screenresolution argv=screenresolution get 05:30:17 INFO - 2016-03-09 05:30:17.202 screenresolution[1645:7675] Display 0: 1600x1200x32@60 05:30:17 INFO - 2016-03-09 05:30:17.208 screenresolution[1646:7677] starting screenresolution argv=screenresolution list 05:30:17 INFO - Available Modes on Display 0 05:30:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:17 INFO - Intel Iris: 05:30:17 INFO - Chipset Model: Intel Iris 05:30:17 INFO - Type: GPU 05:30:17 INFO - Bus: Built-In 05:30:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:17 INFO - Vendor: Intel (0x8086) 05:30:17 INFO - Device ID: 0x0a2e 05:30:17 INFO - Revision ID: 0x0009 05:30:17 INFO - Displays: 05:30:17 INFO - Display: 05:30:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:17 INFO - Display Serial Number: 1600x1200 60 05:30:17 INFO - Main Display: Yes 05:30:17 INFO - Mirror: Off 05:30:17 INFO - Online: Yes 05:30:17 INFO - Rotation: Supported 05:30:17 INFO - Return code: 0 05:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:17 INFO - Python 2.7.3 05:30:17 INFO - Return code: 0 05:30:17 INFO - grabbing minidump binary from tooltool 05:30: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:30:17 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 0x1005ded30>, '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 0x100402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:17 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:30:17 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:30:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:17 INFO - Return code: 0 05:30:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:30:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:30:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:30:18 INFO - Return code: 0 05:30:18 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', '--title', 't-yosemite-r7-0023', '--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:30:18 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 --title t-yosemite-r7-0023 --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:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hgTjSD2eD9/Render', 05:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:18 INFO - 'HOME': '/Users/cltbld', 05:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:18 INFO - 'LOGNAME': 'cltbld', 05:30:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:18 INFO - 'MOZ_NO_REMOTE': '1', 05:30:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:18 INFO - 'NO_EM_RESTART': '1', 05:30:18 INFO - 'PAGER': '/bin/cat', 05:30:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:18 INFO - 'PWD': '/builds/slave/test', 05:30:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:18 INFO - 'SHELL': '/bin/bash', 05:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qlxwp9ic6v/Listeners', 05:30:18 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:18 INFO - 'USER': 'cltbld', 05:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:18 INFO - 'XPC_FLAGS': '0x0', 05:30:18 INFO - 'XPC_SERVICE_NAME': '0', 05:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:18 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', '--title', 't-yosemite-r7-0023', '--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:30:18 INFO - mozversion application_buildid: 20160309030419 05:30:18 INFO - mozversion application_changeset: af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 05:30:18 INFO - mozversion application_display_name: Nightly 05:30:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:18 INFO - mozversion application_name: Firefox 05:30:18 INFO - mozversion application_remotingname: firefox 05:30:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:30:18 INFO - mozversion application_vendor: Mozilla 05:30:18 INFO - mozversion application_version: 48.0a1 05:30:18 INFO - mozversion platform_buildid: 20160309030419 05:30:18 INFO - mozversion platform_changeset: af7c0cb0798f5425d5d344cbaf0ac0ecb1a72a86 05:30:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:30:18 INFO - mozversion platform_version: 48.0a1 05:30:18 INFO - using testdate: 1457530218 05:30:18 INFO - actual date: 1457530218 05:30:18 INFO - starting webserver on 'localhost:49271' 05:30:18 INFO - SUITE-START | Running 2 tests 05:30:18 INFO - TEST-START | dromaeo_css 05:30:18 INFO - operating with platform_type : mac_ 05:30:18 INFO - Initialising browser for dromaeo_css test... 05:30:18 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp6l3iux/profile http://localhost:49271/getInfo.html) 05:30:21 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:30:21 INFO - PROCESS | 1664 | colorDepth:24 05:30:21 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:30:21 INFO - PROCESS | 1664 | __metrics 05:30:21 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:30:21 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:30:22 INFO - TEST-INFO | 1664: exit 0 05:30:22 INFO - Browser initialized. 05:30:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:22 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmp6l3iux/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:34 INFO - PROCESS | 1669 | RSS: Main: 243060736 05:30:34 INFO - PROCESS | 1669 | 05:31:25 INFO - PROCESS | 1669 | 1457530285005 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:33:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:33:36 INFO - PROCESS | 1669 | RSS: Main: 347721728 05:33:36 INFO - PROCESS | 1669 | 05:36:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:36:39 INFO - PROCESS | 1669 | RSS: Main: 374718464 05:36:39 INFO - PROCESS | 1669 | 05:39:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:39:42 INFO - PROCESS | 1669 | RSS: Main: 374607872 05:39:42 INFO - PROCESS | 1669 | 05:42:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:42:45 INFO - PROCESS | 1669 | RSS: Main: 331804672 05:42:45 INFO - PROCESS | 1669 | 05:45:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:45:48 INFO - PROCESS | 1669 | RSS: Main: 333676544 05:45:48 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:48:52 INFO - PROCESS | 1669 | RSS: Main: 382861312 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | __start_tp_report 05:48:52 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:48:52 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:48:52 INFO - PROCESS | 1669 | |i|pagename|runs| 05:48:52 INFO - PROCESS | 1669 | |0;dojo.html;3421.2815308785152;3508.9298214035703;3521.806300653464;3536.664016918395;3577.5885773136083;5698.287714035746;5734.197212390263;5774.422557744234;5784.884302313952;5794.652320860746;5692.459216374448;5697.4302569743;5751.367349591542;5920.792772252972;5922.141289513018;4667.813287468514;4895.55939965403;5149.716765577892;5185.170372740372;5309.62832601718;5354.518093371596;5405.108157154064;5406.8918621627545;5459.454054594548;5476.26070480486;5159.019786240602;5181.248718935771;5269.499397557226;5280.287161233223;5344.599155063366;5221.347331583552;5263.842084737464;5271.182966640177;5274.762635681365;5316.654417462892;4292.957070429318;5218.712970786605;5221.39953905302;5227.18978558324;5258.342707161605;5560.972195139039;5654.123610840326;5667.631603945731;5694.800681976111;5706.771729130871;6141.539384546177;6162.96918515409;6252.71862766172;6253.37466253371;6258.374162583706;6256.311805701369;6267.843303917393;6294.716737746812;6302.55882088254;6323.272823625296;5525.944740552623;5772.105323674838;5785.363610002896;5807.332156801937;5807.622504537191;6260.467860231931;6303.9684801575695;6307.148534947815;6319.462845658079;6326.367363263636;6084.117802918552;6155.169052177988;6157.983932651063;6179.783707570214;6180.783672571439;6139.154842350747;6232.501399887998;6264.029075493306;6273.435390814849;6277.058441233851;6104.216637118914;6162.137300777895;6174.228221472316;6175.876482470326;6187.412195841478;6152.538559608047;6243.063540468966;6261.499080073583;6267.373262673787;6269.435750782452;4913.091078150554;4914.459409464956;4919.950800492021;4932.704037757746;4935.90128197434;4389.253826849444;4392.912141757147;4430.512643609265;4477.6417886569;4516.638668906479;5657.717114144359;5675.687837168995;5729.341125770507;5732.226149469769;5734.483896449339;6262.530310226751;6272.749090036439;6284.748610055546;6290.779822706183;6301.842453938597;5660.349059858169;5728.484436400744;5781.075027995501;5782.768689252467;5786.826395208151;4577.748223847654;5360.758765855546;5366.6779993200535;5393.164059570773;5434.429384914527;5317.680939143586;5334.3865455473115;6305.96847015762;6400.231972163184;6420.582662126947;6147.477464415396;6254.093156492374;6263.154474145932;6284.277308109626;6311.053341998737;6317;6370.948793449029;6386.393292637192;6393.808185754435;6413.16628838248;6157;6198.81403557894;6199.256089269316;6214.1921550198185;6216.7824126155365;6152.846178845564;6172;6257.5932564383165;6281.026440901667;6295.370462953668;6109.495762817132;6113.841195312599;6135.9079613806725;6141.894458997244;6164.722587483664;4835.105505481497;6115.877682446415;6120.5366933153;6123.357047509948;6143.450468055707;5808.5062769665055;5907.232059832194;5921.822345329647;5946.940530594638;5981.252343457068;6131.294901086254;6143.631382117087;6149.985252433298;6157.138000679819;6162.137300777984;6016.578839481194;6017.06735456005;6018.518518518596;6019.759209631566;6060.848478788065 05:48:52 INFO - PROCESS | 1669 | |1;ext.html;501.2256064380534;519.7609099814085;597.1520440973817;633.8859005379032;634.4765568406065;25055;31167.064988050366;39389.60610393895;40031.999200020015;40124.19751604966;43154;43660.78169609147;43663;43679.56320436794;43834.56165438345;9530.237580993522;9788.119069283763;9788.265880059023;9789.90210097897;9818.165455936218;9629.470379129145;9756.560954757053;9768.462734549597;9781.951090244538;9782.706518804447;9708.41749495029;9811.166050885684;9906.801863962717;9926;9930.65242716505;9801.215902727765;9884.80230395388;9904.356216845918;9904.752381190456;9906.157976572;9738.9513052435;9871.90128098717;9875.358101723365;9886.555105020292;9894.30739848211;41281.793591031856;41495.37756933648;41547.58452415467;41548.37677434811;41638.79180604108;3130.874765009397;3238.8380580970856;3406.403879321109;3473.3053389322235;3680.1719613087107;29694.554581681292;30597;31288.904888328798;31298.843505782326;31331.216719581964;2731.262559109049;2760.530709779322;2819.619351387557;2829.63214782077;2883.639545056868;370773.1461342703;375086;375858;376842;376893;69051.65474172698;70481.2951870485;70581.64709176421;73759.63120184364;75175.62412187904;79013;79482.6025869867;79663.80504292324;79723.60138199388;79763;17738.645227095385;17954.5511362217;17957.820421795615;17997.910010449865;18003.459896203134;13193.670158246121;13283.667908302177;13313.933430332787;13328.800067998987;13365.732685346238;20214.989250537416;22561.43596410103;22922.280828937895;23934.922928468262;23956.52086958251;10138.746531336776;10242.641507547349;10290.434026128634;10427.061564459083;10451.477426128664;835.482001159273;965.9806803863884;978.3396207559869;1007.4761124215366;1014.8615332825171;24925.87537062303;27946.720532794818;29238.707612924023;29374.706252937624;29928;938.3384713776771;1011.6661501704419;1089.215764649451;1099.4008265495324;1136.6476392318407;13814.792778108336;15008.399664013536;15215.391384344503;15266.618334541725;15299.923500382427;494992;497777;502154.8491605552;504235;505086;51823.74088129611;59874;61042;61103.38896610977;61112.777744444866;1234.6110975042832;1308.4242933109401;1436.1167881752647;1452.6005348529238;1535.3935195597676;28582.57126143068;28704.712952870203;29226.70773292325;29444.908284629804;29485.705142949155;47082.529174707815;47424.05151897012;47811.28283075687;47894.04211915812;48021;333880;333921;334067.31865363044;335420;336011;30423.391532169677;30859.537106942964;34623;35053.824730876186;35127.12182195466;43055.569444305154;47196.528034720584;48769;48814;48847;36706.63293367032;36864.89405317845;37291.62708372882;37605.62394376021;37710;35754.821225893706;35836;36067.63932360748;36121.63878361183;36866;36977.630223697415;37014.07464813293;37795;38454.2309153821;38977.61022389853;30764.230894227825;32058.519122213653;33379.499307510894;33480;33540.832295838365;21632.026558804577;24286.63570046486;24451.633225501275;24570.63144052805;25169.370765731004 05:48:52 INFO - PROCESS | 1669 | |2;jquery.html;361.2774451097804;361.79377754679825;361.9167591453967;372.8825419992702;374.1880120139297;20297.28959486418;21117.366479005617;21407.357779266647;21527.923603819825;21847.453813654625;17463.301467941266;21024.57950840982;21319.467013324676;21806.018729157186;21921.903904804774;5744.770209191638;5897.970510147465;5910.4089591040765;5932.495737862287;5975.282966044081;5680.6591604503865;5750.511206547449;5838.241028666267;5851.79518716845;5859.29688437386;5774.942250577504;5822.7088645567555;5823.4467725566;5833.679147646878;5839.211706419643;5630.0991841305195;5678.261825962619;5779.190913272145;5805.274340707411;5811.593188476808;5026.974865125651;5058.595312375038;5066.41736200338;5072.163093089635;5082.466341034212;25716;26125.608115878276;26507.73492265072;26603.866980665167;26686;2442.511497700449;2465.704115506133;2583.940584360422;2848.757855582278;2851.743343099131;12319.199252048587;14498.565043048726;14746.410143594243;15207.859410544143;15394.615134621725;1736.9652606947916;1766.240516577871;1790.1586254460383;1792.6325103353781;1857.0065015216794;105427;106937;108576.4571177139;109502;111640;10561.524731387106;10829.783404332027;11030.448477576088;11070.889291107147;11150.776984460266;13227.470901164039;13242.933785331012;13294.800577991338;13298.468061277634;13304.866951330363;7915.366770658448;8048.637811298506;8479.406441549156;8683.56582170889;8766.167214114648;7308.451866110063;8817.823643527221;8824.294056475466;8884.422512536665;9005.684801032063;5890.440408161217;6089.512838972872;6343.809685709436;6432.356764323624;6476.902846457307;1786.857051435882;1813.3109418420916;1817.264008076731;1821.6174603333448;1823.8632102592358;950.8621249918723;970.9708708738749;1006.9496525173712;1011.6661501704419;1034.4310629153936;15528;21252.36242912715;21412.78587214139;21420.357389278346;21526;936.6253498600478;996.2129917365345;1004.6634377483625;1004.8342023566175;1049.2130901823632;11396.373199473943;11704.590339338083;11724.88275117238;11735.23720958135;11787.528498860122;585703;628043;631171;644120;649321;106215.4689226549;107711.46144269228;108713;109126;112573;1035.6582327831798;1045.4563626913962;1058.687687132298;1060.1306928318704;1064.6646306413454;16427.6857851374;17118.657626847642;19063.714044289623;19184.424467266006;19371.709424358927;46951.765241173576;47843.76078119587;47901;48733;51314;64971.35028649653;67404.32595674177;67629.66185169043;68469;70792.2301942435;7946.364290856834;8093.959530202312;8121.187881211831;8140.063892652421;8380.53907035119;12105.213161144497;13324.600261992156;13374.933125334312;13478.32608369558;13888.305584720925;11443;11606.187566870576;11628.767424651629;11762.294262344287;11923.224990375598;7749.721295986099;11056.557737690402;11158.274712143684;11347.829782553434;11644.941775291069;10845.566177352817;11017.394043327373;11849.822252666389;11886;12155.088368372584;7926.643301051351;8065;8104.392170587112;8288.461250018729;8504.829903402022;11607.593734219261;11618.651440456462;11861.525538978689;11892;11924.701882453008 05:48:52 INFO - PROCESS | 1669 | |3;mootools.html;1448.0804689022468;1594.6651203247318;1630.771691963125;1910.9044547772608;2044.754629444467;8124.025116985955;8670.393072484929;8836.690715824947;8844.823103537925;8862.423942443736;8523;8741.606627701753;8746.300295976322;8759.255463285628;8763.12368763125;2168.7831216878344;2434.3427274635837;2475.3192871960264;2498.6626805381225;2499.9250022499264;2435.744246854081;2442.1696623148127;2447.5227330670527;2448;2448.9387765305833;2428.089466450081;2474.591692370757;2482.1064416810023;2485.1053620696534;2485.6023036314195;2187.3000639795273;2416.9274921752376;2478.5042991401792;2479.913203037894;2481.2308184462872;2455.2388759484616;2459.7909177719916;2498.4128729748495;2501.0746023971064;2502.737212592688;8812.559372031374;8933.19601235892;8937.86593201096;8946.23956963659;8972.371933964625;4938.789996450857;5443.428440013781;5470.699111548864;5565.582581306418;5632.549396048306;3617.674409303149;3677.9448308275396;3751.2497500499735;3797.487339209199;3801.2777572261457;1044.185535282481;1047.0576481166934;1048.580567772897;1063.7659714862718;1078.6116997880756;12256.509739610405;12259.877401226053;12262.81605775914;12275.508979640803;12280.324582148067;4284.785760711952;4898.436679781799;4914.041761856403;5026.170681837529;5031.471695471996;10276.691699248884;10292.485375731183;10304.587816487257;10308.433036183082;10312.535935882903;5587.55299576041;5622.184783206412;5622.184783206412;5626.746796394172;5631.380548139701;4445;4632.3977882875015;4812.470628230892;4975.925361119586;5010.649254552146;3893.5133108361456;3990.660793832557;3997.520297564311;4032.3951407288555;4059.939100913489;1296.714722760991;1305.2705165655545;1305.5430599290362;1313.6978494946216;1318.960431187066;1085.2132204152085;1098.1708809848512;1331.8135461035463;1347.3667376333108;1372.457879137735;7903.1306556279915;7912.367010639135;7947.562884041433;7956.085050219299;7967.203279672102;506.22418922719663;521.6452812087741;527.7809708970811;529.6504307157257;530.6497711510384;5325.786968521294;6233.719482623293;6253.593516421418;6268.686565671698;6318.810435686937;10455;10880.401577913291;10895.618653347095;10925.125989920787;10982.945085274523;7575.431842611826;9093.909060909305;10620.89379106199;10856.457177141112;10928.672139835817;8181.181881811771;8301.6679332826;8331.50010999342;8356.582170891432;8432.072472028072;8143.837123257384;8148.918510814816;8232.62953167097;8234;8296.70961516344;9488;9631.8441786986;9679.758006049906;9717.465539395118;9758.170555502578;9857.556409961713;10046.748831279276;10188.439635820101;10232.181425486093;10236.181105511392;6891.620960847202;7192.71229150849;7537.434692398202;7612.1246056704185;7722.498037627536;8316.584170791315;8382.748517544484;8395.076541580544;8400.747977560683;8403.201695838885;7267.345938865527;7382.224866388952;7420.5176663516695;7467.887981680387;7596.316331530189;7184.173820010766;7209.315115064061;7224.760324299484;7320.084989376328;7326.597037163007;7836.294733474015;7843.215678432111;7889.0138732658415;7897.526148431112;7908.130105688485;7085.291470852873;7111.857762844817;7119.359257666835;7173.641317933978;7194.73365889743;7208.171060328129;7216.819579510344;7252.927470725225;7260.564366138048;7261.963690181516 05:48:52 INFO - PROCESS | 1669 | |4;prototype.html;324.0521474686542;326.5020843214098;329.7971747375362;332.7437872837915;336.06908862451013;6490.675466226688;6581.308962558935;6585.308542603024;6587.077809106729;6589.374009469105;6511.2186537615435;6530.902036469445;6541.051547525606;6541.476681865452;6544.269766233249;4635.860924172263;4726.291056341542;4782.78477468513;4809.110314591794;4908.509149085098;4251.404803327537;4522.665813585;4810.711357318554;4821.614270858323;4837.685550439228;4762.547557981986;4773.689710168828;4779.832705855296;4784.258439941815;4791.993681326926;4583;4637.489876113625;4750.952490475085;4769.928451073237;4782.067496838101;4074.7147699661036;4080.510338759368;4082.4080508326424;4091.3249313863175;4122;6142.84642883927;6153.507719382484;6170.413810688023;6189.783357582508;6229.937700622981;5584.497395234448;5591.74837132326;5602.24369710088;5603.299587551556;5626.549876009909;6870.419803445236;6877.89683154753;6885.1049363582915;6890.2076261230095;6891.207511136183;4257.425247591566;4447.755373454459;4467.128909862578;4566.954330456686;4576.3821884045565;7141.571505709674;7152.213256541776;7208.026916366276;7215.891761623579;7249.456290778213;3709.2952339056033;4493.98885250816;4528.773561321921;4537.137943790669;4544.409226800495;4594.7702614869795;4609.907801843944;4610.861674149781;4612.769361531977;4626.1210370029585;5445.074337362578;5457.126859702428;5464.480874316933;5465.289512363447;5465.508104270555;6683.66326734655;6911.619860907598;6914.757983470555;6916.688749006306;6921.48088893335;1579.8104227492774;1594.0117127381097;1602.8958117722311;1609.1712767924528;1611.3554578168826;970.088117169858;1012.5443550402348;1013.4881884648205;1029.7785976015193;1044.6187141693226;6073.1497590337385;6370.458511026614;6494.837629059311;6587.703553340111;6614.900776488356;6312.747490100344;7299.927000730031;7302.2697730226555;7311.597862117635;7334.66993985272;6551.148350714377;6569.638669873108;6584.967075164594;6587.209534855848;6587.505937054644;3801.486799282117;3855.7879316637295;3866.052817059838;3870.7096967727534;3875.3218186817194;7265.346118849329;7559.508631938907;7612.3148916597775;7633.427492938052;7659.770206893802;7443.813904652318;7449.85100297991;7455.739049133254;7468.141163766128;7470.70117195317;7597.582132982739;7606.3914886808925;7608.125065617525;7611;7613.314801667876;5889.734961926638;6984.301569842975;7256.891146632699;7279.745208917662;7289.744858929912;7091.326323999212;7144.892826607501;7148.320909513588;7164.283571642794;7172.605506697182;6436.613803171825;7400.962995185205;7567.054118235221;7595.924040759522;7624.771256862303;3821.636944490269;4579.496255411968;4582.289745089516;4583.862484125481;4591.494935557019;6871.793846184622;7204.0994875640545;7207.387372073433;7212.675429605785;7251.782446526613;5395.78416863321;6150.630962142286;6615.140031795836;6984.930150698631;6997.755078572225;6899.344562266577;6962.443004559522;6965.199002114821;6965.47758918073;6971.198312193959;5628.521575666115;6801.251862295243;6813.671334089805;6824.7952561423235;6829.6243706596615;6717.529772916045;6970.407515361048;6997.860042799217;6998.055262539696;7012.403945664676;6616.2722100567935;6674.799756007327;6768.526203165928;6815.250322464624;6864.38220560152 05:48:52 INFO - PROCESS | 1669 | |5;yui.html;652.8106849013786;680.3366717450483;699.3705664901591;762.6720510180622;797.4975765267882;11419.600313989002;15436.845631543682;15450.61373465664;15463.922680386611;15481.84518154818;14039.227842468661;14047;14858.14273215848;15026.323815428304;15038.624034399118;747.1855677311729;931.3061768982101;933.0809152984305;948.9762755931088;949.7625593601599;753.837924846158;928.3408779766374;950.4107453378915;952.6665667016523;956.8469044952809;1001.6544292219173;1002.5087707023542;1004.5228516454698;1005.6228914157191;1005.8994100589919;783.6434422337823;807.5316316536395;994.393420013791;994.7314225159203;995.4574756757548;597.0357872036673;599.2778701664483;734.5409119300438;747.2303527366821;748.2143749063516;15277.847221527754;15352.923235383752;15356.308966096552;15367;15374.846251537454;12561.623151305475;12598.496060157582;12668.36658167088;12746.80879786804;13007.414666340037;9862.211023118134;9969.700908972742;10292.279540432173;10630.149588032938;10796.67609971702;3590.9461358079648;3667.7982710950887;3671.192337685705;3758.473813666089;3772.1889793694486;80345;80753.59623201846;81013.59493202555;81411.18588814096;81457.9999999994;9551.665691700757;9808.21534277256;9845.80308393828;9861.260405469475;9874.851877221847;21661.350159494927;21661.67507487389;21691.67462488064;21699.78300217009;21703.34889953304;7482.2517748224745;7491.213422590549;7531.8117047074265;7601.65792539326;7629.3133617973535;4055.5538890721996;4132.40080188371;4152.086540960984;4156;4247.68142389322;3269.6893795089427;3271.6401195868434;3309.520119582647;3311.536384906115;3322.534845121685;534.9919751203735;649.1074772188241;651.6481100205931;658.2265837640848;674.4065222604077;577.9225623554505;585.6632436349116;613.5827637206653;621.3072424247023;628.8616504369031;8007.639656215484;8400.243978042006;8476.321894248445;8534.274586660204;8580.227779499875;528.2287859724768;573.9196512488255;595.7946249179387;615.8152554233712;625.7059182184366;3784.280986612535;3824.674902633252;3834.750741201813;3902.8829135125425;4088.5707000765087;90983.5450822755;92462;92529.53735231416;92677;93990;79367;79619;80084;80503;80581;62800.68599656972;67361.64234251974;67688.98466523105;67783;68569;6689.5986240825705;7254.891176632249;7884.172161923145;7914.248146426311;8068.273855353046;33107.50338744873;34444.82777586096;47996.52003479921;48264.03471930612;49101.50898490969;64182;65452.01821972579;70621;70789.29210708033;72407.63796180986;9719.271054670786;9958.55186516623;10130.645427410005;10173.982601739766;10745.409002504624;11079.889201107886;11195.888041119484;11423.143264255381;11455.198136130392;11571.958523732905;10585.576576936835;10591.4704264788;10862.456877156268;10950.561977521129;11010;10584.523696433525;10620.468976551296;10730.54249682878;10953.34279943206;10990;10246.487675616338;10399.94800025995;10578.576856925945;10591.5233814477;10798.352098873778;8953.28373730087;9165.954170229106;9189.540522973959;9283.025282345256;9451.243900488082;8651.6971905983;9018;9036.917658996701;9061.728148155566;9175.357724959189 05:48:52 INFO - PROCESS | 1669 | __end_tp_report 05:48:52 INFO - PROCESS | 1669 | __start_cc_report 05:48:52 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1032 05:48:52 INFO - PROCESS | 1669 | __end_cc_report 05:48:52 INFO - PROCESS | 1669 | __startTimestamp1457531332264__endTimestamp 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:48:52 INFO - PROCESS | 1669 | Number of tests: 6 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5742.75 Median:5981.25 stddev:644.36 (10.8%) stddev-sans-first:620.07 05:48:52 INFO - PROCESS | 1669 | Values: 3421.3 3508.9 3521.8 3536.7 3577.6 5698.3 5734.2 5774.4 5784.9 5794.7 5692.5 5697.4 5751.4 5920.8 5922.1 4667.8 4895.6 5149.7 5185.2 5309.6 5354.5 5405.1 5406.9 5459.5 5476.3 5159.0 5181.2 5269.5 5280.3 5344.6 5221.3 5263.8 5271.2 5274.8 5316.7 4293.0 5218.7 5221.4 5227.2 5258.3 5561.0 5654.1 5667.6 5694.8 5706.8 6141.5 6163.0 6252.7 6253.4 6258.4 6256.3 6267.8 6294.7 6302.6 6323.3 5525.9 5772.1 5785.4 5807.3 5807.6 6260.5 6304.0 6307.1 6319.5 6326.4 6084.1 6155.2 6158.0 6179.8 6180.8 6139.2 6232.5 6264.0 6273.4 6277.1 6104.2 6162.1 6174.2 6175.9 6187.4 6152.5 6243.1 6261.5 6267.4 6269.4 4913.1 4914.5 4920.0 4932.7 4935.9 4389.3 4392.9 4430.5 4477.6 4516.6 5657.7 5675.7 5729.3 5732.2 5734.5 6262.5 6272.7 6284.7 6290.8 6301.8 5660.3 5728.5 5781.1 5782.8 5786.8 4577.7 5360.8 5366.7 5393.2 5434.4 5317.7 5334.4 6306.0 6400.2 6420.6 6147.5 6254.1 6263.2 6284.3 6311.1 6317.0 6370.9 6386.4 6393.8 6413.2 6157.0 6198.8 6199.3 6214.2 6216.8 6152.8 6172.0 6257.6 6281.0 6295.4 6109.5 6113.8 6135.9 6141.9 6164.7 4835.1 6115.9 6120.5 6123.4 6143.5 5808.5 5907.2 5921.8 5946.9 5981.3 6131.3 6143.6 6150.0 6157.1 6162.1 6016.6 6017.1 6018.5 6019.8 6060.8 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:57330.68 Median:36995.85 stddev:108437.06 (293.1%) stddev-sans-first:108657.31 05:48:52 INFO - PROCESS | 1669 | Values: 501.2 519.8 597.2 633.9 634.5 25055.0 31167.1 39389.6 40032.0 40124.2 43154.0 43660.8 43663.0 43679.6 43834.6 9530.2 9788.1 9788.3 9789.9 9818.2 9629.5 9756.6 9768.5 9782.0 9782.7 9708.4 9811.2 9906.8 9926.0 9930.7 9801.2 9884.8 9904.4 9904.8 9906.2 9739.0 9871.9 9875.4 9886.6 9894.3 41281.8 41495.4 41547.6 41548.4 41638.8 3130.9 3238.8 3406.4 3473.3 3680.2 29694.6 30597.0 31288.9 31298.8 31331.2 2731.3 2760.5 2819.6 2829.6 2883.6 370773.1 375086.0 375858.0 376842.0 376893.0 69051.7 70481.3 70581.6 73759.6 75175.6 79013.0 79482.6 79663.8 79723.6 79763.0 17738.6 17954.6 17957.8 17997.9 18003.5 13193.7 13283.7 13313.9 13328.8 13365.7 20215.0 22561.4 22922.3 23934.9 23956.5 10138.7 10242.6 10290.4 10427.1 10451.5 835.5 966.0 978.3 1007.5 1014.9 24925.9 27946.7 29238.7 29374.7 29928.0 938.3 1011.7 1089.2 1099.4 1136.6 13814.8 15008.4 15215.4 15266.6 15299.9 494992.0 497777.0 502154.8 504235.0 505086.0 51823.7 59874.0 61042.0 61103.4 61112.8 1234.6 1308.4 1436.1 1452.6 1535.4 28582.6 28704.7 29226.7 29444.9 29485.7 47082.5 47424.1 47811.3 47894.0 48021.0 333880.0 333921.0 334067.3 335420.0 336011.0 30423.4 30859.5 34623.0 35053.8 35127.1 43055.6 47196.5 48769.0 48814.0 48847.0 36706.6 36864.9 37291.6 37605.6 37710.0 35754.8 35836.0 36067.6 36121.6 36866.0 36977.6 37014.1 37795.0 38454.2 38977.6 30764.2 32058.5 33379.5 33480.0 33540.8 21632.0 24286.6 24451.6 24570.6 25169.4 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:34852.73 Median:21526.96 stddev:103767.74 (482.0%) stddev-sans-first:104026.52 05:48:52 INFO - PROCESS | 1669 | Values: 361.3 361.8 361.9 372.9 374.2 20297.3 21117.4 21407.4 21527.9 21847.5 17463.3 21024.6 21319.5 21806.0 21921.9 5744.8 5898.0 5910.4 5932.5 5975.3 5680.7 5750.5 5838.2 5851.8 5859.3 5774.9 5822.7 5823.4 5833.7 5839.2 5630.1 5678.3 5779.2 5805.3 5811.6 5027.0 5058.6 5066.4 5072.2 5082.5 25716.0 26125.6 26507.7 26603.9 26686.0 2442.5 2465.7 2583.9 2848.8 2851.7 12319.2 14498.6 14746.4 15207.9 15394.6 1737.0 1766.2 1790.2 1792.6 1857.0 105427.0 106937.0 108576.5 109502.0 111640.0 10561.5 10829.8 11030.4 11070.9 11150.8 13227.5 13242.9 13294.8 13298.5 13304.9 7915.4 8048.6 8479.4 8683.6 8766.2 7308.5 8817.8 8824.3 8884.4 9005.7 5890.4 6089.5 6343.8 6432.4 6476.9 1786.9 1813.3 1817.3 1821.6 1823.9 950.9 971.0 1006.9 1011.7 1034.4 15528.0 21252.4 21412.8 21420.4 21526.0 936.6 996.2 1004.7 1004.8 1049.2 11396.4 11704.6 11724.9 11735.2 11787.5 585703.0 628043.0 631171.0 644120.0 649321.0 106215.5 107711.5 108713.0 109126.0 112573.0 1035.7 1045.5 1058.7 1060.1 1064.7 16427.7 17118.7 19063.7 19184.4 19371.7 46951.8 47843.8 47901.0 48733.0 51314.0 64971.4 67404.3 67629.7 68469.0 70792.2 7946.4 8094.0 8121.2 8140.1 8380.5 12105.2 13324.6 13374.9 13478.3 13888.3 11443.0 11606.2 11628.8 11762.3 11923.2 7749.7 11056.6 11158.3 11347.8 11644.9 10845.6 11017.4 11849.8 11886.0 12155.1 7926.6 8065.0 8104.4 8288.5 8504.8 11607.6 11618.7 11861.5 11892.0 11924.7 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:6082.36 Median:528.72 stddev:3239.35 (612.7%) stddev-sans-first:3229.71 05:48:52 INFO - PROCESS | 1669 | Values: 1448.1 1594.7 1630.8 1910.9 2044.8 8124.0 8670.4 8836.7 8844.8 8862.4 8523.0 8741.6 8746.3 8759.3 8763.1 2168.8 2434.3 2475.3 2498.7 2499.9 2435.7 2442.2 2447.5 2448.0 2448.9 2428.1 2474.6 2482.1 2485.1 2485.6 2187.3 2416.9 2478.5 2479.9 2481.2 2455.2 2459.8 2498.4 2501.1 2502.7 8812.6 8933.2 8937.9 8946.2 8972.4 4938.8 5443.4 5470.7 5565.6 5632.5 3617.7 3677.9 3751.2 3797.5 3801.3 1044.2 1047.1 1048.6 1063.8 1078.6 12256.5 12259.9 12262.8 12275.5 12280.3 4284.8 4898.4 4914.0 5026.2 5031.5 10276.7 10292.5 10304.6 10308.4 10312.5 5587.6 5622.2 5622.2 5626.7 5631.4 4445.0 4632.4 4812.5 4975.9 5010.6 3893.5 3990.7 3997.5 4032.4 4059.9 1296.7 1305.3 1305.5 1313.7 1319.0 1085.2 1098.2 1331.8 1347.4 1372.5 7903.1 7912.4 7947.6 7956.1 7967.2 506.2 521.6 527.8 529.7 530.6 5325.8 6233.7 6253.6 6268.7 6318.8 10455.0 10880.4 10895.6 10925.1 10982.9 7575.4 9093.9 10620.9 10856.5 10928.7 8181.2 8301.7 8331.5 8356.6 8432.1 8143.8 8148.9 8232.6 8234.0 8296.7 9488.0 9631.8 9679.8 9717.5 9758.2 9857.6 10046.7 10188.4 10232.2 10236.2 6891.6 7192.7 7537.4 7612.1 7722.5 8316.6 8382.7 8395.1 8400.7 8403.2 7267.3 7382.2 7420.5 7467.9 7596.3 7184.2 7209.3 7224.8 7320.1 7326.6 7836.3 7843.2 7889.0 7897.5 7908.1 7085.3 7111.9 7119.4 7173.6 7194.7 7208.2 7216.8 7252.9 7260.6 7262.0 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:5663.34 Median:6535.98 stddev:1824.41 (27.9%) stddev-sans-first:1784.97 05:48:52 INFO - PROCESS | 1669 | Values: 324.1 326.5 329.8 332.7 336.1 6490.7 6581.3 6585.3 6587.1 6589.4 6511.2 6530.9 6541.1 6541.5 6544.3 4635.9 4726.3 4782.8 4809.1 4908.5 4251.4 4522.7 4810.7 4821.6 4837.7 4762.5 4773.7 4779.8 4784.3 4792.0 4583.0 4637.5 4751.0 4769.9 4782.1 4074.7 4080.5 4082.4 4091.3 4122.0 6142.8 6153.5 6170.4 6189.8 6229.9 5584.5 5591.7 5602.2 5603.3 5626.5 6870.4 6877.9 6885.1 6890.2 6891.2 4257.4 4447.8 4467.1 4567.0 4576.4 7141.6 7152.2 7208.0 7215.9 7249.5 3709.3 4494.0 4528.8 4537.1 4544.4 4594.8 4609.9 4610.9 4612.8 4626.1 5445.1 5457.1 5464.5 5465.3 5465.5 6683.7 6911.6 6914.8 6916.7 6921.5 1579.8 1594.0 1602.9 1609.2 1611.4 970.1 1012.5 1013.5 1029.8 1044.6 6073.1 6370.5 6494.8 6587.7 6614.9 6312.7 7299.9 7302.3 7311.6 7334.7 6551.1 6569.6 6585.0 6587.2 6587.5 3801.5 3855.8 3866.1 3870.7 3875.3 7265.3 7559.5 7612.3 7633.4 7659.8 7443.8 7449.9 7455.7 7468.1 7470.7 7597.6 7606.4 7608.1 7611.0 7613.3 5889.7 6984.3 7256.9 7279.7 7289.7 7091.3 7144.9 7148.3 7164.3 7172.6 6436.6 7401.0 7567.1 7595.9 7624.8 3821.6 4579.5 4582.3 4583.9 4591.5 6871.8 7204.1 7207.4 7212.7 7251.8 5395.8 6150.6 6615.1 6984.9 6997.8 6899.3 6962.4 6965.2 6965.5 6971.2 5628.5 6801.3 6813.7 6824.8 6829.6 6717.5 6970.4 6997.9 6998.1 7012.4 6616.3 6674.8 6768.5 6815.3 6864.4 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18001.33 Median:618.56 stddev:25575.73 (4134.7%) stddev-sans-first:25614.31 05:48:52 INFO - PROCESS | 1669 | Values: 652.8 680.3 699.4 762.7 797.5 11419.6 15436.8 15450.6 15463.9 15481.8 14039.2 14047.0 14858.1 15026.3 15038.6 747.2 931.3 933.1 949.0 949.8 753.8 928.3 950.4 952.7 956.8 1001.7 1002.5 1004.5 1005.6 1005.9 783.6 807.5 994.4 994.7 995.5 597.0 599.3 734.5 747.2 748.2 15277.8 15352.9 15356.3 15367.0 15374.8 12561.6 12598.5 12668.4 12746.8 13007.4 9862.2 9969.7 10292.3 10630.1 10796.7 3590.9 3667.8 3671.2 3758.5 3772.2 80345.0 80753.6 81013.6 81411.2 81458.0 9551.7 9808.2 9845.8 9861.3 9874.9 21661.4 21661.7 21691.7 21699.8 21703.3 7482.3 7491.2 7531.8 7601.7 7629.3 4055.6 4132.4 4152.1 4156.0 4247.7 3269.7 3271.6 3309.5 3311.5 3322.5 535.0 649.1 651.6 658.2 674.4 577.9 585.7 613.6 621.3 628.9 8007.6 8400.2 8476.3 8534.3 8580.2 528.2 573.9 595.8 615.8 625.7 3784.3 3824.7 3834.8 3902.9 4088.6 90983.5 92462.0 92529.5 92677.0 93990.0 79367.0 79619.0 80084.0 80503.0 80581.0 62800.7 67361.6 67689.0 67783.0 68569.0 6689.6 7254.9 7884.2 7914.2 8068.3 33107.5 34444.8 47996.5 48264.0 49101.5 64182.0 65452.0 70621.0 70789.3 72407.6 9719.3 9958.6 10130.6 10174.0 10745.4 11079.9 11195.9 11423.1 11455.2 11572.0 10585.6 10591.5 10862.5 10950.6 11010.0 10584.5 10620.5 10730.5 10953.3 10990.0 10246.5 10399.9 10578.6 10591.5 10798.4 8953.3 9166.0 9189.5 9283.0 9451.2 8651.7 9018.0 9036.9 9061.7 9175.4 05:48:52 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:48:52 INFO - PROCESS | 1669 | 05:48:52 INFO - TEST-INFO | 1669: exit 0 05:48:52 INFO - TEST-OK | dromaeo_css | took 1113917ms 05:48:52 INFO - TEST-START | kraken 05:48:52 INFO - operating with platform_type : mac_ 05:48:52 INFO - Initialising browser for kraken test... 05:48:52 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpVHo_EM/profile http://localhost:49271/getInfo.html) 05:48:55 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:48:55 INFO - PROCESS | 1674 | colorDepth:24 05:48:55 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:48:55 INFO - PROCESS | 1674 | __metrics 05:48:55 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:48:55 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:48:55 INFO - TEST-INFO | 1674: exit 0 05:48:55 INFO - Browser initialized. 05:48:55 INFO - Running cycle 1/1 for kraken test... 05:48:55 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpVHo_EM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:08 INFO - PROCESS | 1677 | RSS: Main: 243200000 05:49:08 INFO - PROCESS | 1677 | 05:49:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:11 INFO - PROCESS | 1677 | RSS: Main: 288223232 05:49:11 INFO - PROCESS | 1677 | 05:49:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:49:14 INFO - PROCESS | 1677 | RSS: Main: 299220992 05:49:14 INFO - PROCESS | 1677 | 05:49:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:49:18 INFO - PROCESS | 1677 | RSS: Main: 285450240 05:49:18 INFO - PROCESS | 1677 | 05:49:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:49:20 INFO - PROCESS | 1677 | RSS: Main: 287944704 05:49:20 INFO - PROCESS | 1677 | 05:49:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:23 INFO - PROCESS | 1677 | RSS: Main: 287596544 05:49:23 INFO - PROCESS | 1677 | 05:49:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:49:26 INFO - PROCESS | 1677 | RSS: Main: 286547968 05:49:26 INFO - PROCESS | 1677 | 05:49:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:49:30 INFO - PROCESS | 1677 | RSS: Main: 288722944 05:49:30 INFO - PROCESS | 1677 | 05:49:33 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:49:33 INFO - PROCESS | 1677 | RSS: Main: 289259520 05:49:33 INFO - PROCESS | 1677 | 05:49:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:49:35 INFO - PROCESS | 1677 | RSS: Main: 323248128 05:49:35 INFO - PROCESS | 1677 | 05:49:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:38 INFO - PROCESS | 1677 | RSS: Main: 323256320 05:49:38 INFO - PROCESS | 1677 | 05:49:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:40 INFO - PROCESS | 1677 | RSS: Main: 331042816 05:49:40 INFO - PROCESS | 1677 | 05:49:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:44 INFO - PROCESS | 1677 | RSS: Main: 333189120 05:49:44 INFO - PROCESS | 1677 | 05:49:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:47 INFO - PROCESS | 1677 | RSS: Main: 332455936 05:49:47 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:49:49 INFO - PROCESS | 1677 | RSS: Main: 392933376 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | __start_tp_report 05:49:49 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:49:49 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:49:49 INFO - PROCESS | 1677 | |i|pagename|runs| 05:49:49 INFO - PROCESS | 1677 | |0;ai-astar;110;108;112;109;111;110;116;110;111;111 05:49:49 INFO - PROCESS | 1677 | |1;audio-beat-detection;154;125;139;157;119;139;139;118;157;119 05:49:49 INFO - PROCESS | 1677 | |2;audio-dft;186;188;188;186;185;184;189;186;183;182 05:49:49 INFO - PROCESS | 1677 | |3;audio-fft;76;76;83;77;55;59;57;82;76;60 05:49:49 INFO - PROCESS | 1677 | |4;audio-oscillator;92;100;128;110;112;122;114;75;79;119 05:49:49 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;87;85;88;89;134;129;88;84;87;84 05:49:49 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;94;95;95;92;94;94;105;150;93 05:49:49 INFO - PROCESS | 1677 | |7;imaging-desaturate;85;88;86;86;85;84;86;85;87;85 05:49:49 INFO - PROCESS | 1677 | |8;json-parse-financial;58;55;54;52;53;54;56;64;55;51 05:49:49 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;64;74;72;73;67;65;65;65;68 05:49:49 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;83;83;77;84;83;82;84;81;83;82 05:49:49 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;117;130;120;126;120;121;119;123;128;135 05:49:49 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;139;128;160;175;177;135;138;148;175;171 05:49:49 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;65;67;64;67;67;66;67;67;68;66 05:49:49 INFO - PROCESS | 1677 | __end_tp_report 05:49:49 INFO - PROCESS | 1677 | __start_cc_report 05:49:49 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1248 05:49:49 INFO - PROCESS | 1677 | __end_cc_report 05:49:49 INFO - PROCESS | 1677 | __startTimestamp1457531389301__endTimestamp 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:49:49 INFO - PROCESS | 1677 | Number of tests: 14 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:2.15 (1.9%) stddev-sans-first:2.26 05:49:49 INFO - PROCESS | 1677 | Values: 110.0 108.0 112.0 109.0 111.0 110.0 116.0 110.0 111.0 111.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:139.00 stddev:15.82 (11.4%) stddev-sans-first:15.48 05:49:49 INFO - PROCESS | 1677 | Values: 154.0 125.0 139.0 157.0 119.0 139.0 139.0 118.0 157.0 119.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:2.26 (1.2%) stddev-sans-first:2.40 05:49:49 INFO - PROCESS | 1677 | Values: 186.0 188.0 188.0 186.0 185.0 184.0 189.0 186.0 183.0 182.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:70.10 Median:76.00 stddev:10.98 (14.4%) stddev-sans-first:11.44 05:49:49 INFO - PROCESS | 1677 | Values: 76.0 76.0 83.0 77.0 55.0 59.0 57.0 82.0 76.0 60.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:125.00 stddev:18.07 (14.5%) stddev-sans-first:18.53 05:49:49 INFO - PROCESS | 1677 | Values: 92.0 100.0 128.0 110.0 112.0 122.0 114.0 75.0 79.0 119.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:87.00 stddev:19.09 (21.9%) stddev-sans-first:19.99 05:49:49 INFO - PROCESS | 1677 | Values: 87.0 85.0 88.0 89.0 134.0 129.0 88.0 84.0 87.0 84.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:94.00 stddev:17.77 (18.9%) stddev-sans-first:18.64 05:49:49 INFO - PROCESS | 1677 | Values: 93.0 94.0 95.0 95.0 92.0 94.0 94.0 105.0 150.0 93.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.16 (1.3%) stddev-sans-first:1.20 05:49:49 INFO - PROCESS | 1677 | Values: 85.0 88.0 86.0 86.0 85.0 84.0 86.0 85.0 87.0 85.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:3.68 (6.7%) stddev-sans-first:3.76 05:49:49 INFO - PROCESS | 1677 | Values: 58.0 55.0 54.0 52.0 53.0 54.0 56.0 64.0 55.0 51.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:67.50 stddev:3.89 (5.8%) stddev-sans-first:3.89 05:49:49 INFO - PROCESS | 1677 | Values: 64.0 64.0 74.0 72.0 73.0 67.0 65.0 65.0 65.0 68.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.00 stddev:2.04 (2.5%) stddev-sans-first:2.15 05:49:49 INFO - PROCESS | 1677 | Values: 83.0 83.0 77.0 84.0 83.0 82.0 84.0 81.0 83.0 82.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:124.50 stddev:5.70 (4.6%) stddev-sans-first:5.48 05:49:49 INFO - PROCESS | 1677 | Values: 117.0 130.0 120.0 126.0 120.0 121.0 119.0 123.0 128.0 135.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:165.50 stddev:19.11 (11.5%) stddev-sans-first:19.42 05:49:49 INFO - PROCESS | 1677 | Values: 139.0 128.0 160.0 175.0 177.0 135.0 138.0 148.0 175.0 171.0 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.13 05:49:49 INFO - PROCESS | 1677 | Values: 65.0 67.0 64.0 67.0 67.0 66.0 67.0 67.0 68.0 66.0 05:49:49 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:49:49 INFO - PROCESS | 1677 | 05:49:49 INFO - TEST-INFO | 1677: exit 0 05:49:50 INFO - TEST-OK | kraken | took 57172ms 05:49:50 INFO - SUITE-END | took 1171s 05:49:50 INFO - Completed test suite (00:19:31) 05:49:50 INFO - javascript benchmark 05:49:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3421.2815308785152, 3508.9298214035703, 3521.806300653464, 3536.664016918395, 3577.5885773136083, 5698.287714035746, 5734.197212390263, 5774.422557744234, 5784.884302313952, 5794.652320860746, 5692.459216374448, 5697.4302569743, 5751.367349591542, 5920.792772252972, 5922.141289513018, 4667.813287468514, 4895.55939965403, 5149.716765577892, 5185.170372740372, 5309.62832601718, 5354.518093371596, 5405.108157154064, 5406.8918621627545, 5459.454054594548, 5476.26070480486, 5159.019786240602, 5181.248718935771, 5269.499397557226, 5280.287161233223, 5344.599155063366, 5221.347331583552, 5263.842084737464, 5271.182966640177, 5274.762635681365, 5316.654417462892, 4292.957070429318, 5218.712970786605, 5221.39953905302, 5227.18978558324, 5258.342707161605, 5560.972195139039, 5654.123610840326, 5667.631603945731, 5694.800681976111, 5706.771729130871, 6141.539384546177, 6162.96918515409, 6252.71862766172, 6253.37466253371, 6258.374162583706, 6256.311805701369, 6267.843303917393, 6294.716737746812, 6302.55882088254, 6323.272823625296, 5525.944740552623, 5772.105323674838, 5785.363610002896, 5807.332156801937, 5807.622504537191, 6260.467860231931, 6303.9684801575695, 6307.148534947815, 6319.462845658079, 6326.367363263636, 6084.117802918552, 6155.169052177988, 6157.983932651063, 6179.783707570214, 6180.783672571439, 6139.154842350747, 6232.501399887998, 6264.029075493306, 6273.435390814849, 6277.058441233851, 6104.216637118914, 6162.137300777895, 6174.228221472316, 6175.876482470326, 6187.412195841478, 6152.538559608047, 6243.063540468966, 6261.499080073583, 6267.373262673787, 6269.435750782452, 4913.091078150554, 4914.459409464956, 4919.950800492021, 4932.704037757746, 4935.90128197434, 4389.253826849444, 4392.912141757147, 4430.512643609265, 4477.6417886569, 4516.638668906479, 5657.717114144359, 5675.687837168995, 5729.341125770507, 5732.226149469769, 5734.483896449339, 6262.530310226751, 6272.749090036439, 6284.748610055546, 6290.779822706183, 6301.842453938597, 5660.349059858169, 5728.484436400744, 5781.075027995501, 5782.768689252467, 5786.826395208151, 4577.748223847654, 5360.758765855546, 5366.6779993200535, 5393.164059570773, 5434.429384914527, 5317.680939143586, 5334.3865455473115, 6305.96847015762, 6400.231972163184, 6420.582662126947, 6147.477464415396, 6254.093156492374, 6263.154474145932, 6284.277308109626, 6311.053341998737, 6317.0, 6370.948793449029, 6386.393292637192, 6393.808185754435, 6413.16628838248, 6157.0, 6198.81403557894, 6199.256089269316, 6214.1921550198185, 6216.7824126155365, 6152.846178845564, 6172.0, 6257.5932564383165, 6281.026440901667, 6295.370462953668, 6109.495762817132, 6113.841195312599, 6135.9079613806725, 6141.894458997244, 6164.722587483664, 4835.105505481497, 6115.877682446415, 6120.5366933153, 6123.357047509948, 6143.450468055707, 5808.5062769665055, 5907.232059832194, 5921.822345329647, 5946.940530594638, 5981.252343457068, 6131.294901086254, 6143.631382117087, 6149.985252433298, 6157.138000679819, 6162.137300777984, 6016.578839481194, 6017.06735456005, 6018.518518518596, 6019.759209631566, 6060.848478788065], "unit": "score", "name": "dojo.html", "value": 5703.6227784249695}, {"lowerIsBetter": false, "replicates": [501.2256064380534, 519.7609099814085, 597.1520440973817, 633.8859005379032, 634.4765568406065, 25055.0, 31167.064988050366, 39389.60610393895, 40031.999200020015, 40124.19751604966, 43154.0, 43660.78169609147, 43663.0, 43679.56320436794, 43834.56165438345, 9530.237580993522, 9788.119069283763, 9788.265880059023, 9789.90210097897, 9818.165455936218, 9629.470379129145, 9756.560954757053, 9768.462734549597, 9781.951090244538, 9782.706518804447, 9708.41749495029, 9811.166050885684, 9906.801863962717, 9926.0, 9930.65242716505, 9801.215902727765, 9884.80230395388, 9904.356216845918, 9904.752381190456, 9906.157976572, 9738.9513052435, 9871.90128098717, 9875.358101723365, 9886.555105020292, 9894.30739848211, 41281.793591031856, 41495.37756933648, 41547.58452415467, 41548.37677434811, 41638.79180604108, 3130.874765009397, 3238.8380580970856, 3406.403879321109, 3473.3053389322235, 3680.1719613087107, 29694.554581681292, 30597.0, 31288.904888328798, 31298.843505782326, 31331.216719581964, 2731.262559109049, 2760.530709779322, 2819.619351387557, 2829.63214782077, 2883.639545056868, 370773.1461342703, 375086.0, 375858.0, 376842.0, 376893.0, 69051.65474172698, 70481.2951870485, 70581.64709176421, 73759.63120184364, 75175.62412187904, 79013.0, 79482.6025869867, 79663.80504292324, 79723.60138199388, 79763.0, 17738.645227095385, 17954.5511362217, 17957.820421795615, 17997.910010449865, 18003.459896203134, 13193.670158246121, 13283.667908302177, 13313.933430332787, 13328.800067998987, 13365.732685346238, 20214.989250537416, 22561.43596410103, 22922.280828937895, 23934.922928468262, 23956.52086958251, 10138.746531336776, 10242.641507547349, 10290.434026128634, 10427.061564459083, 10451.477426128664, 835.482001159273, 965.9806803863884, 978.3396207559869, 1007.4761124215366, 1014.8615332825171, 24925.87537062303, 27946.720532794818, 29238.707612924023, 29374.706252937624, 29928.0, 938.3384713776771, 1011.6661501704419, 1089.215764649451, 1099.4008265495324, 1136.6476392318407, 13814.792778108336, 15008.399664013536, 15215.391384344503, 15266.618334541725, 15299.923500382427, 494992.0, 497777.0, 502154.8491605552, 504235.0, 505086.0, 51823.74088129611, 59874.0, 61042.0, 61103.38896610977, 61112.777744444866, 1234.6110975042832, 1308.4242933109401, 1436.1167881752647, 1452.6005348529238, 1535.3935195597676, 28582.57126143068, 28704.712952870203, 29226.70773292325, 29444.908284629804, 29485.705142949155, 47082.529174707815, 47424.05151897012, 47811.28283075687, 47894.04211915812, 48021.0, 333880.0, 333921.0, 334067.31865363044, 335420.0, 336011.0, 30423.391532169677, 30859.537106942964, 34623.0, 35053.824730876186, 35127.12182195466, 43055.569444305154, 47196.528034720584, 48769.0, 48814.0, 48847.0, 36706.63293367032, 36864.89405317845, 37291.62708372882, 37605.62394376021, 37710.0, 35754.821225893706, 35836.0, 36067.63932360748, 36121.63878361183, 36866.0, 36977.630223697415, 37014.07464813293, 37795.0, 38454.2309153821, 38977.61022389853, 30764.230894227825, 32058.519122213653, 33379.499307510894, 33480.0, 33540.832295838365, 21632.026558804577, 24286.63570046486, 24451.633225501275, 24570.63144052805, 25169.370765731004], "unit": "score", "name": "ext.html", "value": 19928.1085854636}, {"lowerIsBetter": false, "replicates": [361.2774451097804, 361.79377754679825, 361.9167591453967, 372.8825419992702, 374.1880120139297, 20297.28959486418, 21117.366479005617, 21407.357779266647, 21527.923603819825, 21847.453813654625, 17463.301467941266, 21024.57950840982, 21319.467013324676, 21806.018729157186, 21921.903904804774, 5744.770209191638, 5897.970510147465, 5910.4089591040765, 5932.495737862287, 5975.282966044081, 5680.6591604503865, 5750.511206547449, 5838.241028666267, 5851.79518716845, 5859.29688437386, 5774.942250577504, 5822.7088645567555, 5823.4467725566, 5833.679147646878, 5839.211706419643, 5630.0991841305195, 5678.261825962619, 5779.190913272145, 5805.274340707411, 5811.593188476808, 5026.974865125651, 5058.595312375038, 5066.41736200338, 5072.163093089635, 5082.466341034212, 25716.0, 26125.608115878276, 26507.73492265072, 26603.866980665167, 26686.0, 2442.511497700449, 2465.704115506133, 2583.940584360422, 2848.757855582278, 2851.743343099131, 12319.199252048587, 14498.565043048726, 14746.410143594243, 15207.859410544143, 15394.615134621725, 1736.9652606947916, 1766.240516577871, 1790.1586254460383, 1792.6325103353781, 1857.0065015216794, 105427.0, 106937.0, 108576.4571177139, 109502.0, 111640.0, 10561.524731387106, 10829.783404332027, 11030.448477576088, 11070.889291107147, 11150.776984460266, 13227.470901164039, 13242.933785331012, 13294.800577991338, 13298.468061277634, 13304.866951330363, 7915.366770658448, 8048.637811298506, 8479.406441549156, 8683.56582170889, 8766.167214114648, 7308.451866110063, 8817.823643527221, 8824.294056475466, 8884.422512536665, 9005.684801032063, 5890.440408161217, 6089.512838972872, 6343.809685709436, 6432.356764323624, 6476.902846457307, 1786.857051435882, 1813.3109418420916, 1817.264008076731, 1821.6174603333448, 1823.8632102592358, 950.8621249918723, 970.9708708738749, 1006.9496525173712, 1011.6661501704419, 1034.4310629153936, 15528.0, 21252.36242912715, 21412.78587214139, 21420.357389278346, 21526.0, 936.6253498600478, 996.2129917365345, 1004.6634377483625, 1004.8342023566175, 1049.2130901823632, 11396.373199473943, 11704.590339338083, 11724.88275117238, 11735.23720958135, 11787.528498860122, 585703.0, 628043.0, 631171.0, 644120.0, 649321.0, 106215.4689226549, 107711.46144269228, 108713.0, 109126.0, 112573.0, 1035.6582327831798, 1045.4563626913962, 1058.687687132298, 1060.1306928318704, 1064.6646306413454, 16427.6857851374, 17118.657626847642, 19063.714044289623, 19184.424467266006, 19371.709424358927, 46951.765241173576, 47843.76078119587, 47901.0, 48733.0, 51314.0, 64971.35028649653, 67404.32595674177, 67629.66185169043, 68469.0, 70792.2301942435, 7946.364290856834, 8093.959530202312, 8121.187881211831, 8140.063892652421, 8380.53907035119, 12105.213161144497, 13324.600261992156, 13374.933125334312, 13478.32608369558, 13888.305584720925, 11443.0, 11606.187566870576, 11628.767424651629, 11762.294262344287, 11923.224990375598, 7749.721295986099, 11056.557737690402, 11158.274712143684, 11347.829782553434, 11644.941775291069, 10845.566177352817, 11017.394043327373, 11849.822252666389, 11886.0, 12155.088368372584, 7926.643301051351, 8065.0, 8104.392170587112, 8288.461250018729, 8504.829903402022, 11607.593734219261, 11618.651440456462, 11861.525538978689, 11892.0, 11924.701882453008], "unit": "score", "name": "jquery.html", "value": 9690.256113832853}, {"lowerIsBetter": false, "replicates": [1448.0804689022468, 1594.6651203247318, 1630.771691963125, 1910.9044547772608, 2044.754629444467, 8124.025116985955, 8670.393072484929, 8836.690715824947, 8844.823103537925, 8862.423942443736, 8523.0, 8741.606627701753, 8746.300295976322, 8759.255463285628, 8763.12368763125, 2168.7831216878344, 2434.3427274635837, 2475.3192871960264, 2498.6626805381225, 2499.9250022499264, 2435.744246854081, 2442.1696623148127, 2447.5227330670527, 2448.0, 2448.9387765305833, 2428.089466450081, 2474.591692370757, 2482.1064416810023, 2485.1053620696534, 2485.6023036314195, 2187.3000639795273, 2416.9274921752376, 2478.5042991401792, 2479.913203037894, 2481.2308184462872, 2455.2388759484616, 2459.7909177719916, 2498.4128729748495, 2501.0746023971064, 2502.737212592688, 8812.559372031374, 8933.19601235892, 8937.86593201096, 8946.23956963659, 8972.371933964625, 4938.789996450857, 5443.428440013781, 5470.699111548864, 5565.582581306418, 5632.549396048306, 3617.674409303149, 3677.9448308275396, 3751.2497500499735, 3797.487339209199, 3801.2777572261457, 1044.185535282481, 1047.0576481166934, 1048.580567772897, 1063.7659714862718, 1078.6116997880756, 12256.509739610405, 12259.877401226053, 12262.81605775914, 12275.508979640803, 12280.324582148067, 4284.785760711952, 4898.436679781799, 4914.041761856403, 5026.170681837529, 5031.471695471996, 10276.691699248884, 10292.485375731183, 10304.587816487257, 10308.433036183082, 10312.535935882903, 5587.55299576041, 5622.184783206412, 5622.184783206412, 5626.746796394172, 5631.380548139701, 4445.0, 4632.3977882875015, 4812.470628230892, 4975.925361119586, 5010.649254552146, 3893.5133108361456, 3990.660793832557, 3997.520297564311, 4032.3951407288555, 4059.939100913489, 1296.714722760991, 1305.2705165655545, 1305.5430599290362, 1313.6978494946216, 1318.960431187066, 1085.2132204152085, 1098.1708809848512, 1331.8135461035463, 1347.3667376333108, 1372.457879137735, 7903.1306556279915, 7912.367010639135, 7947.562884041433, 7956.085050219299, 7967.203279672102, 506.22418922719663, 521.6452812087741, 527.7809708970811, 529.6504307157257, 530.6497711510384, 5325.786968521294, 6233.719482623293, 6253.593516421418, 6268.686565671698, 6318.810435686937, 10455.0, 10880.401577913291, 10895.618653347095, 10925.125989920787, 10982.945085274523, 7575.431842611826, 9093.909060909305, 10620.89379106199, 10856.457177141112, 10928.672139835817, 8181.181881811771, 8301.6679332826, 8331.50010999342, 8356.582170891432, 8432.072472028072, 8143.837123257384, 8148.918510814816, 8232.62953167097, 8234.0, 8296.70961516344, 9488.0, 9631.8441786986, 9679.758006049906, 9717.465539395118, 9758.170555502578, 9857.556409961713, 10046.748831279276, 10188.439635820101, 10232.181425486093, 10236.181105511392, 6891.620960847202, 7192.71229150849, 7537.434692398202, 7612.1246056704185, 7722.498037627536, 8316.584170791315, 8382.748517544484, 8395.076541580544, 8400.747977560683, 8403.201695838885, 7267.345938865527, 7382.224866388952, 7420.5176663516695, 7467.887981680387, 7596.316331530189, 7184.173820010766, 7209.315115064061, 7224.760324299484, 7320.084989376328, 7326.597037163007, 7836.294733474015, 7843.215678432111, 7889.0138732658415, 7897.526148431112, 7908.130105688485, 7085.291470852873, 7111.857762844817, 7119.359257666835, 7173.641317933978, 7194.73365889743, 7208.171060328129, 7216.819579510344, 7252.927470725225, 7260.564366138048, 7261.963690181516], "unit": "score", "name": "mootools.html", "value": 4869.648262537583}, {"lowerIsBetter": false, "replicates": [324.0521474686542, 326.5020843214098, 329.7971747375362, 332.7437872837915, 336.06908862451013, 6490.675466226688, 6581.308962558935, 6585.308542603024, 6587.077809106729, 6589.374009469105, 6511.2186537615435, 6530.902036469445, 6541.051547525606, 6541.476681865452, 6544.269766233249, 4635.860924172263, 4726.291056341542, 4782.78477468513, 4809.110314591794, 4908.509149085098, 4251.404803327537, 4522.665813585, 4810.711357318554, 4821.614270858323, 4837.685550439228, 4762.547557981986, 4773.689710168828, 4779.832705855296, 4784.258439941815, 4791.993681326926, 4583.0, 4637.489876113625, 4750.952490475085, 4769.928451073237, 4782.067496838101, 4074.7147699661036, 4080.510338759368, 4082.4080508326424, 4091.3249313863175, 4122.0, 6142.84642883927, 6153.507719382484, 6170.413810688023, 6189.783357582508, 6229.937700622981, 5584.497395234448, 5591.74837132326, 5602.24369710088, 5603.299587551556, 5626.549876009909, 6870.419803445236, 6877.89683154753, 6885.1049363582915, 6890.2076261230095, 6891.207511136183, 4257.425247591566, 4447.755373454459, 4467.128909862578, 4566.954330456686, 4576.3821884045565, 7141.571505709674, 7152.213256541776, 7208.026916366276, 7215.891761623579, 7249.456290778213, 3709.2952339056033, 4493.98885250816, 4528.773561321921, 4537.137943790669, 4544.409226800495, 4594.7702614869795, 4609.907801843944, 4610.861674149781, 4612.769361531977, 4626.1210370029585, 5445.074337362578, 5457.126859702428, 5464.480874316933, 5465.289512363447, 5465.508104270555, 6683.66326734655, 6911.619860907598, 6914.757983470555, 6916.688749006306, 6921.48088893335, 1579.8104227492774, 1594.0117127381097, 1602.8958117722311, 1609.1712767924528, 1611.3554578168826, 970.088117169858, 1012.5443550402348, 1013.4881884648205, 1029.7785976015193, 1044.6187141693226, 6073.1497590337385, 6370.458511026614, 6494.837629059311, 6587.703553340111, 6614.900776488356, 6312.747490100344, 7299.927000730031, 7302.2697730226555, 7311.597862117635, 7334.66993985272, 6551.148350714377, 6569.638669873108, 6584.967075164594, 6587.209534855848, 6587.505937054644, 3801.486799282117, 3855.7879316637295, 3866.052817059838, 3870.7096967727534, 3875.3218186817194, 7265.346118849329, 7559.508631938907, 7612.3148916597775, 7633.427492938052, 7659.770206893802, 7443.813904652318, 7449.85100297991, 7455.739049133254, 7468.141163766128, 7470.70117195317, 7597.582132982739, 7606.3914886808925, 7608.125065617525, 7611.0, 7613.314801667876, 5889.734961926638, 6984.301569842975, 7256.891146632699, 7279.745208917662, 7289.744858929912, 7091.326323999212, 7144.892826607501, 7148.320909513588, 7164.283571642794, 7172.605506697182, 6436.613803171825, 7400.962995185205, 7567.054118235221, 7595.924040759522, 7624.771256862303, 3821.636944490269, 4579.496255411968, 4582.289745089516, 4583.862484125481, 4591.494935557019, 6871.793846184622, 7204.0994875640545, 7207.387372073433, 7212.675429605785, 7251.782446526613, 5395.78416863321, 6150.630962142286, 6615.140031795836, 6984.930150698631, 6997.755078572225, 6899.344562266577, 6962.443004559522, 6965.199002114821, 6965.47758918073, 6971.198312193959, 5628.521575666115, 6801.251862295243, 6813.671334089805, 6824.7952561423235, 6829.6243706596615, 6717.529772916045, 6970.407515361048, 6997.860042799217, 6998.055262539696, 7012.403945664676, 6616.2722100567935, 6674.799756007327, 6768.526203165928, 6815.250322464624, 6864.38220560152], "unit": "score", "name": "prototype.html", "value": 5053.219561424443}, {"lowerIsBetter": false, "replicates": [652.8106849013786, 680.3366717450483, 699.3705664901591, 762.6720510180622, 797.4975765267882, 11419.600313989002, 15436.845631543682, 15450.61373465664, 15463.922680386611, 15481.84518154818, 14039.227842468661, 14047.0, 14858.14273215848, 15026.323815428304, 15038.624034399118, 747.1855677311729, 931.3061768982101, 933.0809152984305, 948.9762755931088, 949.7625593601599, 753.837924846158, 928.3408779766374, 950.4107453378915, 952.6665667016523, 956.8469044952809, 1001.6544292219173, 1002.5087707023542, 1004.5228516454698, 1005.6228914157191, 1005.8994100589919, 783.6434422337823, 807.5316316536395, 994.393420013791, 994.7314225159203, 995.4574756757548, 597.0357872036673, 599.2778701664483, 734.5409119300438, 747.2303527366821, 748.2143749063516, 15277.847221527754, 15352.923235383752, 15356.308966096552, 15367.0, 15374.846251537454, 12561.623151305475, 12598.496060157582, 12668.36658167088, 12746.80879786804, 13007.414666340037, 9862.211023118134, 9969.700908972742, 10292.279540432173, 10630.149588032938, 10796.67609971702, 3590.9461358079648, 3667.7982710950887, 3671.192337685705, 3758.473813666089, 3772.1889793694486, 80345.0, 80753.59623201846, 81013.59493202555, 81411.18588814096, 81457.9999999994, 9551.665691700757, 9808.21534277256, 9845.80308393828, 9861.260405469475, 9874.851877221847, 21661.350159494927, 21661.67507487389, 21691.67462488064, 21699.78300217009, 21703.34889953304, 7482.2517748224745, 7491.213422590549, 7531.8117047074265, 7601.65792539326, 7629.3133617973535, 4055.5538890721996, 4132.40080188371, 4152.086540960984, 4156.0, 4247.68142389322, 3269.6893795089427, 3271.6401195868434, 3309.520119582647, 3311.536384906115, 3322.534845121685, 534.9919751203735, 649.1074772188241, 651.6481100205931, 658.2265837640848, 674.4065222604077, 577.9225623554505, 585.6632436349116, 613.5827637206653, 621.3072424247023, 628.8616504369031, 8007.639656215484, 8400.243978042006, 8476.321894248445, 8534.274586660204, 8580.227779499875, 528.2287859724768, 573.9196512488255, 595.7946249179387, 615.8152554233712, 625.7059182184366, 3784.280986612535, 3824.674902633252, 3834.750741201813, 3902.8829135125425, 4088.5707000765087, 90983.5450822755, 92462.0, 92529.53735231416, 92677.0, 93990.0, 79367.0, 79619.0, 80084.0, 80503.0, 80581.0, 62800.68599656972, 67361.64234251974, 67688.98466523105, 67783.0, 68569.0, 6689.5986240825705, 7254.891176632249, 7884.172161923145, 7914.248146426311, 8068.273855353046, 33107.50338744873, 34444.82777586096, 47996.52003479921, 48264.03471930612, 49101.50898490969, 64182.0, 65452.01821972579, 70621.0, 70789.29210708033, 72407.63796180986, 9719.271054670786, 9958.55186516623, 10130.645427410005, 10173.982601739766, 10745.409002504624, 11079.889201107886, 11195.888041119484, 11423.143264255381, 11455.198136130392, 11571.958523732905, 10585.576576936835, 10591.4704264788, 10862.456877156268, 10950.561977521129, 11010.0, 10584.523696433525, 10620.468976551296, 10730.54249682878, 10953.34279943206, 10990.0, 10246.487675616338, 10399.94800025995, 10578.576856925945, 10591.5233814477, 10798.352098873778, 8953.28373730087, 9165.954170229106, 9189.540522973959, 9283.025282345256, 9451.243900488082, 8651.6971905983, 9018.0, 9036.917658996701, 9061.728148155566, 9175.357724959189], "unit": "score", "name": "yui.html", "value": 6811.612697644152}], "name": "dromaeo_css", "value": 7546.009696509931}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 112.0, 109.0, 111.0, 110.0, 116.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.8}, {"lowerIsBetter": true, "replicates": [154.0, 125.0, 139.0, 157.0, 119.0, 139.0, 139.0, 118.0, 157.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 136.6}, {"lowerIsBetter": true, "replicates": [186.0, 188.0, 188.0, 186.0, 185.0, 184.0, 189.0, 186.0, 183.0, 182.0], "unit": "score", "name": "audio-dft", "value": 185.7}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 83.0, 77.0, 55.0, 59.0, 57.0, 82.0, 76.0, 60.0], "unit": "score", "name": "audio-fft", "value": 70.1}, {"lowerIsBetter": true, "replicates": [92.0, 100.0, 128.0, 110.0, 112.0, 122.0, 114.0, 75.0, 79.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 105.1}, {"lowerIsBetter": true, "replicates": [87.0, 85.0, 88.0, 89.0, 134.0, 129.0, 88.0, 84.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 95.5}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 95.0, 95.0, 92.0, 94.0, 94.0, 105.0, 150.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 100.5}, {"lowerIsBetter": true, "replicates": [85.0, 88.0, 86.0, 86.0, 85.0, 84.0, 86.0, 85.0, 87.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 85.7}, {"lowerIsBetter": true, "replicates": [58.0, 55.0, 54.0, 52.0, 53.0, 54.0, 56.0, 64.0, 55.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 74.0, 72.0, 73.0, 67.0, 65.0, 65.0, 65.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 77.0, 84.0, 83.0, 82.0, 84.0, 81.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.2}, {"lowerIsBetter": true, "replicates": [117.0, 130.0, 120.0, 126.0, 120.0, 121.0, 119.0, 123.0, 128.0, 135.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [139.0, 128.0, 160.0, 175.0, 177.0, 135.0, 138.0, 148.0, 175.0, 171.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 154.6}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 64.0, 67.0, 67.0, 66.0, 67.0, 67.0, 68.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.4}], "name": "kraken", "value": 1440.0000000000002}]} 05:49:50 INFO - Return code: 0 05:49:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:50 ERROR - # TBPL SUCCESS # 05:49:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:50 INFO - 2016-03-09 05:49:50.621 screenresolution[1681:9773] starting screenresolution argv=screenresolution get 05:49:50 INFO - 2016-03-09 05:49:50.623 screenresolution[1681:9773] Display 0: 1600x1200x32@60 05:49:50 INFO - 2016-03-09 05:49:50.629 screenresolution[1682:9776] starting screenresolution argv=screenresolution list 05:49:50 INFO - Available Modes on Display 0 05:49:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:50 INFO - Intel Iris: 05:49:50 INFO - Chipset Model: Intel Iris 05:49:50 INFO - Type: GPU 05:49:50 INFO - Bus: Built-In 05:49:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:50 INFO - Vendor: Intel (0x8086) 05:49:50 INFO - Device ID: 0x0a2e 05:49:50 INFO - Revision ID: 0x0009 05:49:50 INFO - Displays: 05:49:50 INFO - Display: 05:49:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:50 INFO - Display Serial Number: 1600x1200 60 05:49:50 INFO - Main Display: Yes 05:49:50 INFO - Mirror: Off 05:49:50 INFO - Online: Yes 05:49:50 INFO - Rotation: Supported 05:49:50 INFO - Return code: 0 05:49:50 INFO - Running post-action listener: _resource_record_post_action 05:49:50 INFO - Running post-run listener: _resource_record_post_run 05:49:51 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 34311680; Write bytes: 704608768; Read time: 348; Write time: 2205 05:49:51 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380619264; Write bytes: 374138880; Read time: 28486; Write time: 691 05:49:51 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 33361408; Write bytes: 301453824; Read time: 334; Write time: 1459 05:49:51 INFO - Running post-run listener: _upload_blobber_files 05:49:51 INFO - Blob upload gear active. 05:49:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:49:51 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:49:51 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:49:51 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:49:52 INFO - (blobuploader) - INFO - Open directory for files ... 05:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:53 INFO - (blobuploader) - INFO - Done attempting. 05:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:54 INFO - (blobuploader) - INFO - Done attempting. 05:49:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:49:54 INFO - Return code: 0 05:49:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:49:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:49:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f67837ec7d562a2e09b3cc46e5ad285dd16c4750ca51f8f5aa067db05a4f091a107e66cb1f85b71dc393dab8fc9fe5aad456bc1d144e34c6a6e0d3816b4fda0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fc320c3cb0b38cc5000109225a37d688504dc4af405e5319903c9af926ea157fb96d014ec8b7a15291df95c3d77c2729481302ce14e6a6bf39d1fa2d014560d"} 05:49:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:49:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:49:54 INFO - Contents: 05:49:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f67837ec7d562a2e09b3cc46e5ad285dd16c4750ca51f8f5aa067db05a4f091a107e66cb1f85b71dc393dab8fc9fe5aad456bc1d144e34c6a6e0d3816b4fda0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fc320c3cb0b38cc5000109225a37d688504dc4af405e5319903c9af926ea157fb96d014ec8b7a15291df95c3d77c2729481302ce14e6a6bf39d1fa2d014560d"} 05:49:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:49:54 INFO - Copying logs to upload dir... 05:49:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:49:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.304104 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-03-09 05:49:54.778215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-09 05:49:54.781526) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/f67837ec7d562a2e09b3cc46e5ad285dd16c4750ca51f8f5aa067db05a4f091a107e66cb1f85b71dc393dab8fc9fe5aad456bc1d144e34c6a6e0d3816b4fda0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fc320c3cb0b38cc5000109225a37d688504dc4af405e5319903c9af926ea157fb96d014ec8b7a15291df95c3d77c2729481302ce14e6a6bf39d1fa2d014560d"} build_url:https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011434 build_url: 'https://queue.taskcluster.net/v1/task/BWFAOzctQfe1UW_Eiky0og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f67837ec7d562a2e09b3cc46e5ad285dd16c4750ca51f8f5aa067db05a4f091a107e66cb1f85b71dc393dab8fc9fe5aad456bc1d144e34c6a6e0d3816b4fda0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fc320c3cb0b38cc5000109225a37d688504dc4af405e5319903c9af926ea157fb96d014ec8b7a15291df95c3d77c2729481302ce14e6a6bf39d1fa2d014560d"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-09 05:49:54.833890) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 05:49:54.834360) ========= 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.hgTjSD2eD9/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.Qlxwp9ic6v/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005319 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-09 05:49:54.946849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-09 05:49:54.947179) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-09 05:49:54.947555) ========= ========= Total master_lag: 8.06 =========