builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1464430184.16 results: success (0) buildid: 20160525183450 builduid: 63f91898e679451db7d589c242ad058b revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.155912) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.156246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.156526) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.184977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.185260) ========= 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.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-05-28 03:09:44-- 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% 147M=0s 2016-05-28 03:09:44 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.652637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.857280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.857585) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.020160 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:44.905674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:44.905974) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-05-28 03:09:44,964 truncating revision to first 12 chars 2016-05-28 03:09:44,964 Setting DEBUG logging. 2016-05-28 03:09:44,965 attempt 1/10 2016-05-28 03:09:44,965 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/33693d8bdec1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 03:09:46,506 unpacking tar archive at: mozilla-aurora-33693d8bdec1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.805109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:46.730790) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:46.731114) ========= script_repo_revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:46.731512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:46.731775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:46.747485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-28 03:09:46.747757) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:09:46 INFO - MultiFileLogger online at 20160528 03:09:46 in /builds/slave/test 03:09:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:09:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:46 INFO - {'append_to_log': False, 03:09:46 INFO - 'base_work_dir': '/builds/slave/test', 03:09:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:09:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:46 INFO - 'branch': 'Mozilla-Aurora', 03:09:46 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:46 INFO - 'config_files': ('talos/mac_config.py',), 03:09:46 INFO - 'default_actions': ('clobber', 03:09:46 INFO - 'read-buildbot-config', 03:09:46 INFO - 'download-and-extract', 03:09:46 INFO - 'populate-webroot', 03:09:46 INFO - 'create-virtualenv', 03:09:46 INFO - 'install', 03:09:46 INFO - 'run-tests'), 03:09:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:46 INFO - 'download_minidump_stackwalk': True, 03:09:46 INFO - 'download_symbols': 'ondemand', 03:09:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:46 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:46 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:46 INFO - 'installer_path': 'installer.exe', 03:09:46 INFO - 'log_level': 'info', 03:09:46 INFO - 'log_name': 'talos', 03:09:46 INFO - 'log_to_console': True, 03:09:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:09:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:09:46 INFO - 'opt_config_files': (), 03:09:46 INFO - 'pip_index': False, 03:09:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:46 INFO - 'cmd': ('bash', 03:09:46 INFO - '-c', 03:09:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:46 INFO - 'enabled': True, 03:09:46 INFO - 'halt_on_failure': False, 03:09:46 INFO - 'name': 'check_screen_resolution'},), 03:09:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:46 INFO - 'cmd': ('bash', 03:09:46 INFO - '-c', 03:09:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:46 INFO - 'enabled': True, 03:09:46 INFO - 'halt_on_failure': False, 03:09:46 INFO - 'name': 'check_screen_resolution'},), 03:09:46 INFO - 'run_cmd_checks_enabled': True, 03:09:46 INFO - 'sps_profile': False, 03:09:46 INFO - 'sps_profile_interval': 0, 03:09:46 INFO - 'suite': 'dromaeojs', 03:09:46 INFO - 'system_bits': '32', 03:09:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:46 INFO - 'title': 't-yosemite-r7-0313', 03:09:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:46 INFO - 'use_talos_json': True, 03:09:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:46 INFO - 'work_dir': 'build'} 03:09:46 INFO - ##### 03:09:46 INFO - ##### Running clobber step. 03:09:46 INFO - ##### 03:09:46 INFO - Running pre-action listener: _resource_record_pre_action 03:09:46 INFO - Running main action method: clobber 03:09:46 INFO - rmtree: /builds/slave/test/build 03:09:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:48 INFO - Running post-action listener: _resource_record_post_action 03:09:48 INFO - ##### 03:09:48 INFO - ##### Running read-buildbot-config step. 03:09:48 INFO - ##### 03:09:48 INFO - Running pre-action listener: _resource_record_pre_action 03:09:48 INFO - Running main action method: read_buildbot_config 03:09:48 INFO - Using buildbot properties: 03:09:48 INFO - { 03:09:48 INFO - "project": "", 03:09:48 INFO - "product": "firefox", 03:09:48 INFO - "script_repo_revision": "production", 03:09:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:09:48 INFO - "repository": "", 03:09:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:09:48 INFO - "buildid": "20160525183450", 03:09:48 INFO - "pgo_build": "False", 03:09:48 INFO - "basedir": "/builds/slave/test", 03:09:48 INFO - "buildnumber": 333, 03:09:48 INFO - "slavename": "t-yosemite-r7-0313", 03:09:48 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:09:48 INFO - "platform": "yosemite_r7", 03:09:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:09:48 INFO - "branch": "mozilla-aurora", 03:09:48 INFO - "repo_path": "releases/mozilla-aurora", 03:09:48 INFO - "revision": "33693d8bdec17f07fc460ca7cc908ade88f97b86", 03:09:48 INFO - "stage_platform": "macosx64", 03:09:48 INFO - "builduid": "63f91898e679451db7d589c242ad058b", 03:09:48 INFO - "slavebuilddir": "test" 03:09:48 INFO - } 03:09:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:09:48 INFO - Running post-action listener: _resource_record_post_action 03:09:48 INFO - ##### 03:09:48 INFO - ##### Running download-and-extract step. 03:09:48 INFO - ##### 03:09:48 INFO - Running pre-action listener: _resource_record_pre_action 03:09:48 INFO - Running main action method: download_and_extract 03:09:48 INFO - mkdir: /builds/slave/test/build/tests 03:09:48 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')]} 03:09:48 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:09:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:09:52 INFO - Downloaded 1199 bytes. 03:09:52 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:52 INFO - Using the following test package requirements: 03:09:52 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:09:52 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:09:52 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:09:52 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'jsshell-mac.zip'], 03:09:52 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:09:52 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:09:52 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:09:52 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:09:52 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:09:52 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:52 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:09:52 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:52 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:09:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:09:54 INFO - Downloaded 18425354 bytes. 03:09:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:09:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:09:57 INFO - Return code: 0 03:09:57 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:57 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:09:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:09:58 INFO - Downloaded 18425354 bytes. 03:09:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:10:02 INFO - Return code: 0 03:10:02 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')]} 03:10:02 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:10:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:10:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:10:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:10:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:10:03 INFO - Downloaded 10840145 bytes. 03:10:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:10:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:10:03 INFO - Return code: 0 03:10:03 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')]} 03:10:03 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:10:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:10:10 INFO - Downloaded 114134973 bytes. 03:10:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:10 INFO - mkdir: /builds/slave/test/properties 03:10:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:10 INFO - Contents: 03:10:10 INFO - build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:11 INFO - Running post-action listener: _resource_record_post_action 03:10:11 INFO - Running post-action listener: set_extra_try_arguments 03:10:11 INFO - ##### 03:10:11 INFO - ##### Running populate-webroot step. 03:10:11 INFO - ##### 03:10:11 INFO - Running pre-action listener: _resource_record_pre_action 03:10:11 INFO - Running main action method: populate_webroot 03:10:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:10:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:10:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:10:11 INFO - '--tppagecycles', 03:10:11 INFO - '7'], 03:10:11 INFO - 'tests': ['tsvgm']}}, 03:10:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:10:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:10:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['damp', 'tps']}, 03:10:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'tests': ['damp', 'tps']}, 03:10:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['dromaeo_dom']}, 03:10:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['a11yr', 03:10:11 INFO - 'ts_paint', 03:10:11 INFO - 'tpaint', 03:10:11 INFO - 'sessionrestore', 03:10:11 INFO - 'sessionrestore_no_auto_restore', 03:10:11 INFO - 'tabpaint']}, 03:10:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['tsvgx', 03:10:11 INFO - 'tsvgr_opacity', 03:10:11 INFO - 'tart', 03:10:11 INFO - 'tscrollx', 03:10:11 INFO - 'cart']}, 03:10:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:11 INFO - 'tsvgr_opacity', 03:10:11 INFO - 'tart', 03:10:11 INFO - 'tscrollx', 03:10:11 INFO - 'cart']}, 03:10:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'talos_options': ['--disable-e10s'], 03:10:11 INFO - 'tests': ['tp5o']}, 03:10:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'tests': ['tp5o']}, 03:10:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'talos_options': ['--disable-e10s', 03:10:11 INFO - '--xperf_path', 03:10:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:11 INFO - 'tests': ['tp5n']}, 03:10:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:11 INFO - 'talos_options': ['--xperf_path', 03:10:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:11 INFO - 'tests': ['tp5n']}}, 03:10:11 INFO - 'talos.zip': {'path': '', 03:10:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:10:11 INFO - Running post-action listener: _resource_record_post_action 03:10:11 INFO - ##### 03:10:11 INFO - ##### Running create-virtualenv step. 03:10:11 INFO - ##### 03:10:11 INFO - Running pre-action listener: _resource_record_pre_action 03:10:11 INFO - Running main action method: create_virtualenv 03:10:11 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:11 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 03:10:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:10:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:11 INFO - Using real prefix '/tools/python27' 03:10:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:12 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:15 INFO - Installing pip.................done. 03:10:15 INFO - Return code: 0 03:10:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10:15 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')]} 03:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:15 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')]} 03:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:15 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:15 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 03:10:15 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 03:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:15 INFO - 'HOME': '/Users/cltbld', 03:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:15 INFO - 'LOGNAME': 'cltbld', 03:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:15 INFO - 'MOZ_NO_REMOTE': '1', 03:10:15 INFO - 'NO_EM_RESTART': '1', 03:10:15 INFO - 'PAGER': '/bin/cat', 03:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:15 INFO - 'PWD': '/builds/slave/test', 03:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:15 INFO - 'SHELL': '/bin/bash', 03:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:15 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:15 INFO - 'USER': 'cltbld', 03:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:15 INFO - 'XPC_FLAGS': '0x0', 03:10:15 INFO - 'XPC_SERVICE_NAME': '0', 03:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:15 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 03:10:15 INFO - Cleaning up... 03:10:15 INFO - Return code: 0 03:10:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:10:15 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')]} 03:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:15 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')]} 03:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:15 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:15 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 03:10:15 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 03:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:15 INFO - 'HOME': '/Users/cltbld', 03:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:15 INFO - 'LOGNAME': 'cltbld', 03:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:15 INFO - 'MOZ_NO_REMOTE': '1', 03:10:15 INFO - 'NO_EM_RESTART': '1', 03:10:15 INFO - 'PAGER': '/bin/cat', 03:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:15 INFO - 'PWD': '/builds/slave/test', 03:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:15 INFO - 'SHELL': '/bin/bash', 03:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:15 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:15 INFO - 'USER': 'cltbld', 03:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:15 INFO - 'XPC_FLAGS': '0x0', 03:10:15 INFO - 'XPC_SERVICE_NAME': '0', 03:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:15 INFO - Downloading/unpacking psutil>=0.7.1 03:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:19 INFO - Installing collected packages: psutil 03:10:19 INFO - Running setup.py install for psutil 03:10:19 INFO - building 'psutil._psutil_osx' extension 03:10:19 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 03:10:19 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 03:10:19 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 03:10:19 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 03:10:19 INFO - building 'psutil._psutil_posix' extension 03:10:19 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 03:10:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:10:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:10:19 INFO - ^ 03:10:19 INFO - 1 warning generated. 03:10:19 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 03:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:20 INFO - Successfully installed psutil 03:10:20 INFO - Cleaning up... 03:10:20 INFO - Return code: 0 03:10:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:10: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')]} 03:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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')]} 03:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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', '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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:10: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 mozsystemmonitor==0.0.0 03:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:20 INFO - 'HOME': '/Users/cltbld', 03:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:20 INFO - 'LOGNAME': 'cltbld', 03:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:20 INFO - 'MOZ_NO_REMOTE': '1', 03:10:20 INFO - 'NO_EM_RESTART': '1', 03:10:20 INFO - 'PAGER': '/bin/cat', 03:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:20 INFO - 'PWD': '/builds/slave/test', 03:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:20 INFO - 'SHELL': '/bin/bash', 03:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:20 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:20 INFO - 'USER': 'cltbld', 03:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:20 INFO - 'XPC_FLAGS': '0x0', 03:10:20 INFO - 'XPC_SERVICE_NAME': '0', 03:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:10:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:10:22 INFO - Installing collected packages: mozsystemmonitor 03:10:22 INFO - Running setup.py install for mozsystemmonitor 03:10:23 INFO - Successfully installed mozsystemmonitor 03:10:23 INFO - Cleaning up... 03:10:23 INFO - Return code: 0 03:10:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:10:23 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')]} 03:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:23 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')]} 03:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:23 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:23 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 03:10:23 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 03:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:23 INFO - 'HOME': '/Users/cltbld', 03:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:23 INFO - 'LOGNAME': 'cltbld', 03:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:23 INFO - 'MOZ_NO_REMOTE': '1', 03:10:23 INFO - 'NO_EM_RESTART': '1', 03:10:23 INFO - 'PAGER': '/bin/cat', 03:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:23 INFO - 'PWD': '/builds/slave/test', 03:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:23 INFO - 'SHELL': '/bin/bash', 03:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:23 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:23 INFO - 'USER': 'cltbld', 03:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:23 INFO - 'XPC_FLAGS': '0x0', 03:10:23 INFO - 'XPC_SERVICE_NAME': '0', 03:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10:25 INFO - Downloading blobuploader-1.2.4.tar.gz 03:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:10:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:10:25 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 03:10:25 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 03:10:25 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 03:10:25 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 03:10:25 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 03:10:25 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 03:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:10:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:10:26 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 03:10:26 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 03:10:26 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 03:10:26 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 03:10:26 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 03:10:26 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 03:10:26 INFO - Downloading docopt-0.6.1.tar.gz 03:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:10:27 INFO - Installing collected packages: blobuploader, requests, docopt 03:10:27 INFO - Running setup.py install for blobuploader 03:10:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:10:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:10:27 INFO - Running setup.py install for requests 03:10:27 INFO - Running setup.py install for docopt 03:10:27 INFO - Successfully installed blobuploader requests docopt 03:10:27 INFO - Cleaning up... 03:10:27 INFO - Return code: 0 03:10:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10: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')]} 03:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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')]} 03:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:27 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:27 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 03:10:27 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 03:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:27 INFO - 'HOME': '/Users/cltbld', 03:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:27 INFO - 'LOGNAME': 'cltbld', 03:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:27 INFO - 'MOZ_NO_REMOTE': '1', 03:10:27 INFO - 'NO_EM_RESTART': '1', 03:10:27 INFO - 'PAGER': '/bin/cat', 03:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:27 INFO - 'PWD': '/builds/slave/test', 03:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:27 INFO - 'SHELL': '/bin/bash', 03:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:27 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:27 INFO - 'USER': 'cltbld', 03:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:27 INFO - 'XPC_FLAGS': '0x0', 03:10:27 INFO - 'XPC_SERVICE_NAME': '0', 03:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Iag3cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-n2Jlm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-awkIly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-VTb97e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-4CYZhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-j3WgfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-KmtmeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:28 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-A10raU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-WGTyPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-sjrJff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-S2X0TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-C6AgT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-oR4jKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-fBGFnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-RKVd3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-4NO9yM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:29 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-1x4hd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:10:29 INFO - Running setup.py install for manifestparser 03:10:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:10:30 INFO - Running setup.py install for mozcrash 03:10:30 INFO - Running setup.py install for mozdebug 03:10:30 INFO - Running setup.py install for mozdevice 03:10:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:10:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:10:30 INFO - Running setup.py install for mozfile 03:10:30 INFO - Running setup.py install for mozhttpd 03:10:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:10:30 INFO - Running setup.py install for mozinfo 03:10:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:10:30 INFO - Running setup.py install for mozInstall 03:10:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Running setup.py install for mozleak 03:10:31 INFO - Running setup.py install for mozlog 03:10:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Running setup.py install for moznetwork 03:10:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Running setup.py install for mozprocess 03:10:31 INFO - Running setup.py install for mozprofile 03:10:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:10:31 INFO - Running setup.py install for mozrunner 03:10:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:10:32 INFO - Running setup.py install for mozscreenshot 03:10:32 INFO - Running setup.py install for moztest 03:10:32 INFO - Running setup.py install for mozversion 03:10:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:10:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:10:32 INFO - Cleaning up... 03:10:32 INFO - Return code: 0 03:10:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:32 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:32 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 03:10:32 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 03:10:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:32 INFO - 'HOME': '/Users/cltbld', 03:10:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:32 INFO - 'LOGNAME': 'cltbld', 03:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:32 INFO - 'MOZ_NO_REMOTE': '1', 03:10:32 INFO - 'NO_EM_RESTART': '1', 03:10:32 INFO - 'PAGER': '/bin/cat', 03:10:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:32 INFO - 'PWD': '/builds/slave/test', 03:10:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:32 INFO - 'SHELL': '/bin/bash', 03:10:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:32 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:32 INFO - 'USER': 'cltbld', 03:10:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:32 INFO - 'XPC_FLAGS': '0x0', 03:10:32 INFO - 'XPC_SERVICE_NAME': '0', 03:10:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:32 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-iNwhD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:32 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)) 03:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:32 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-HAF3xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:32 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)) 03:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:32 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-FHv8kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-7cgeZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-53eXL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-FHadl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-_6L02S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-e9ADvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-5j1niO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Uyv9mU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-tRE1E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:33 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)) 03:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:33 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Ce6y0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:34 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)) 03:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:34 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-4uV5jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:34 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)) 03:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:34 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-V6RHNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:34 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)) 03:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:34 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-QtNIFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:34 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)) 03:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:34 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-aVrHs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:34 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)) 03:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:34 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-6710Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:34 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)) 03:10:34 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)) 03:10:34 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)) 03:10:34 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)) 03:10:34 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)) 03:10:34 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)) 03:10:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:36 INFO - Downloading blessings-1.6.tar.gz 03:10:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:10:37 INFO - Installing collected packages: blessings 03:10:37 INFO - Running setup.py install for blessings 03:10:37 INFO - Successfully installed blessings 03:10:37 INFO - Cleaning up... 03:10:37 INFO - Return code: 0 03:10:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:37 INFO - Reading from file tmpfile_stdout 03:10:37 INFO - Current package versions: 03:10:37 INFO - blessings == 1.6 03:10:37 INFO - blobuploader == 1.2.4 03:10:37 INFO - docopt == 0.6.1 03:10:37 INFO - manifestparser == 1.1 03:10:37 INFO - mozInstall == 1.12 03:10:37 INFO - mozcrash == 0.17 03:10:37 INFO - mozdebug == 0.1 03:10:37 INFO - mozdevice == 0.48 03:10:37 INFO - mozfile == 1.2 03:10:37 INFO - mozhttpd == 0.7 03:10:37 INFO - mozinfo == 0.9 03:10:37 INFO - mozleak == 0.1 03:10:37 INFO - mozlog == 3.1 03:10:37 INFO - moznetwork == 0.27 03:10:37 INFO - mozprocess == 0.22 03:10:37 INFO - mozprofile == 0.28 03:10:37 INFO - mozrunner == 6.11 03:10:37 INFO - mozscreenshot == 0.1 03:10:37 INFO - mozsystemmonitor == 0.0 03:10:37 INFO - moztest == 0.7 03:10:37 INFO - mozversion == 1.4 03:10:37 INFO - psutil == 3.1.1 03:10:37 INFO - requests == 1.2.3 03:10:37 INFO - wsgiref == 0.1.2 03:10:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:37 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')]} 03:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:37 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')]} 03:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:37 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:37 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 03:10:37 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 03:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:37 INFO - 'HOME': '/Users/cltbld', 03:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:37 INFO - 'LOGNAME': 'cltbld', 03:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:37 INFO - 'MOZ_NO_REMOTE': '1', 03:10:37 INFO - 'NO_EM_RESTART': '1', 03:10:37 INFO - 'PAGER': '/bin/cat', 03:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:37 INFO - 'PWD': '/builds/slave/test', 03:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:37 INFO - 'SHELL': '/bin/bash', 03:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:37 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:37 INFO - 'USER': 'cltbld', 03:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:37 INFO - 'XPC_FLAGS': '0x0', 03:10:37 INFO - 'XPC_SERVICE_NAME': '0', 03:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 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)) 03:10:37 INFO - Cleaning up... 03:10:37 INFO - Return code: 0 03:10:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:10:37 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')]} 03:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:37 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')]} 03:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:37 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:37 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 03:10:37 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 03:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:37 INFO - 'HOME': '/Users/cltbld', 03:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:37 INFO - 'LOGNAME': 'cltbld', 03:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:37 INFO - 'MOZ_NO_REMOTE': '1', 03:10:37 INFO - 'NO_EM_RESTART': '1', 03:10:37 INFO - 'PAGER': '/bin/cat', 03:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:37 INFO - 'PWD': '/builds/slave/test', 03:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:37 INFO - 'SHELL': '/bin/bash', 03:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:37 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:37 INFO - 'USER': 'cltbld', 03:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:37 INFO - 'XPC_FLAGS': '0x0', 03:10:37 INFO - 'XPC_SERVICE_NAME': '0', 03:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:38 INFO - Downloading/unpacking jsonschema 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:10:40 INFO - Downloading/unpacking functools32 (from jsonschema) 03:10:40 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 03:10:40 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 03:10:40 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 03:10:40 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 03:10:40 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 03:10:40 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 03:10:40 INFO - Downloading functools32-3.2.3-2.tar.gz 03:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:10:41 INFO - warning: no files found matching '*.txt' 03:10:41 INFO - no previously-included directories found matching 'build' 03:10:41 INFO - no previously-included directories found matching 'dist' 03:10:41 INFO - no previously-included directories found matching '.git*' 03:10:41 INFO - Installing collected packages: jsonschema, functools32 03:10:41 INFO - Running setup.py install for functools32 03:10:41 INFO - warning: no files found matching '*.txt' 03:10:41 INFO - no previously-included directories found matching 'build' 03:10:41 INFO - no previously-included directories found matching 'dist' 03:10:41 INFO - no previously-included directories found matching '.git*' 03:10:41 INFO - Successfully installed jsonschema functools32 03:10:41 INFO - Cleaning up... 03:10:41 INFO - Return code: 0 03:10:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:10:41 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')]} 03:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:41 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')]} 03:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:41 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 0x10c1c1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1d1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6dac97b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, '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.oscRCsa6So/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.qPJqbdMg8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 03:10:41 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 03:10:41 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 03:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:41 INFO - 'HOME': '/Users/cltbld', 03:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:41 INFO - 'LOGNAME': 'cltbld', 03:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:41 INFO - 'MOZ_NO_REMOTE': '1', 03:10:41 INFO - 'NO_EM_RESTART': '1', 03:10:41 INFO - 'PAGER': '/bin/cat', 03:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:41 INFO - 'PWD': '/builds/slave/test', 03:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:41 INFO - 'SHELL': '/bin/bash', 03:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:10:41 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:41 INFO - 'USER': 'cltbld', 03:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:41 INFO - 'XPC_FLAGS': '0x0', 03:10:41 INFO - 'XPC_SERVICE_NAME': '0', 03:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:41 INFO - Downloading/unpacking flake8 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:10:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:10:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:10:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:10:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:10:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:10:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:10:45 INFO - no previously-included directories found matching 'docs/_build' 03:10:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:10:45 INFO - Running setup.py install for pep8 03:10:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:10:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:10:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:10:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:10:45 INFO - no previously-included directories found matching 'docs/_build' 03:10:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:10:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:10:45 INFO - Cleaning up... 03:10:45 INFO - Return code: 0 03:10:45 INFO - Running post-action listener: _resource_record_post_action 03:10:45 INFO - Running post-action listener: _start_resource_monitoring 03:10:45 INFO - Starting resource monitoring. 03:10:45 INFO - ##### 03:10:45 INFO - ##### Running install step. 03:10:45 INFO - ##### 03:10:45 INFO - Running pre-action listener: _resource_record_pre_action 03:10:45 INFO - Running main action method: install 03:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:45 INFO - Reading from file tmpfile_stdout 03:10:45 INFO - Detecting whether we're running mozinstall >=1.0... 03:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:10:46 INFO - Reading from file tmpfile_stdout 03:10:46 INFO - Output received: 03:10:46 INFO - Usage: mozinstall [options] installer 03:10:46 INFO - Options: 03:10:46 INFO - -h, --help show this help message and exit 03:10:46 INFO - -d DEST, --destination=DEST 03:10:46 INFO - Directory to install application into. [default: 03:10:46 INFO - "/builds/slave/test"] 03:10:46 INFO - --app=APP Application being installed. [default: firefox] 03:10:46 INFO - mkdir: /builds/slave/test/build/application 03:10:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:11:18 INFO - Reading from file tmpfile_stdout 03:11:18 INFO - Output received: 03:11:18 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:11:18 INFO - Running post-action listener: _resource_record_post_action 03:11:18 INFO - ##### 03:11:18 INFO - ##### Running run-tests step. 03:11:18 INFO - ##### 03:11:18 INFO - Running pre-action listener: _resource_record_pre_action 03:11:18 INFO - Running main action method: run_tests 03:11:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:18 INFO - 2016-05-28 03:11:18.193 screenresolution[2192:22391] starting screenresolution argv=screenresolution get 03:11:18 INFO - 2016-05-28 03:11:18.196 screenresolution[2192:22391] Display 0: 1600x1200x32@60 03:11:18 INFO - 2016-05-28 03:11:18.202 screenresolution[2193:22393] starting screenresolution argv=screenresolution list 03:11:18 INFO - Available Modes on Display 0 03:11:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:18 INFO - Intel Iris: 03:11:18 INFO - Chipset Model: Intel Iris 03:11:18 INFO - Type: GPU 03:11:18 INFO - Bus: Built-In 03:11:18 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:18 INFO - Vendor: Intel (0x8086) 03:11:18 INFO - Device ID: 0x0a2e 03:11:18 INFO - Revision ID: 0x0009 03:11:18 INFO - Displays: 03:11:18 INFO - Display: 03:11:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:18 INFO - Display Serial Number: 1600x1200 60 03:11:18 INFO - Main Display: Yes 03:11:18 INFO - Mirror: Off 03:11:18 INFO - Online: Yes 03:11:18 INFO - Rotation: Supported 03:11:18 INFO - Return code: 0 03:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:11:18 INFO - Python 2.7.3 03:11:18 INFO - Return code: 0 03:11:18 INFO - grabbing minidump binary from tooltool 03:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:18 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 0x10c060e00>, '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 0x10bec9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c05bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:11:18 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 03:11:18 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 03:11:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:11:18 INFO - Return code: 0 03:11:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:11:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:11:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:11:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:11:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:11:19 INFO - Return code: 0 03:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 03:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --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 03:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oscRCsa6So/Render', 03:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:19 INFO - 'HOME': '/Users/cltbld', 03:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:19 INFO - 'LOGNAME': 'cltbld', 03:11:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:11:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:19 INFO - 'MOZ_NO_REMOTE': '1', 03:11:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:11:19 INFO - 'NO_EM_RESTART': '1', 03:11:19 INFO - 'PAGER': '/bin/cat', 03:11:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:19 INFO - 'PWD': '/builds/slave/test', 03:11:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:19 INFO - 'SHELL': '/bin/bash', 03:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qPJqbdMg8U/Listeners', 03:11:19 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 03:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:19 INFO - 'USER': 'cltbld', 03:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:19 INFO - 'XPC_FLAGS': '0x0', 03:11:19 INFO - 'XPC_SERVICE_NAME': '0', 03:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 03:11:19 INFO - mozversion application_buildid: 20160525183450 03:11:19 INFO - mozversion application_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:11:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:11:19 INFO - mozversion application_name: Firefox 03:11:19 INFO - mozversion application_remotingname: firefox-dev 03:11:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:19 INFO - mozversion application_vendor: Mozilla 03:11:19 INFO - mozversion application_version: 48.0a2 03:11:19 INFO - mozversion platform_buildid: 20160525183450 03:11:19 INFO - mozversion platform_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:19 INFO - mozversion platform_version: 48.0a2 03:11:19 INFO - using testdate: 1464430279 03:11:19 INFO - actual date: 1464430279 03:11:19 INFO - starting webserver on 'localhost:55848' 03:11:19 INFO - SUITE-START | Running 2 tests 03:11:19 INFO - TEST-START | dromaeo_css 03:11:19 INFO - operating with platform_type : mac_ 03:11:19 INFO - Initialising browser for dromaeo_css test... 03:11:19 INFO - TEST-INFO | started process 2211 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpCFSgh_/profile http://localhost:55848/getInfo.html) 03:11:21 INFO - PROCESS | 2211 | __metrics Screen width/height:1600/1200 03:11:21 INFO - PROCESS | 2211 | colorDepth:24 03:11:21 INFO - PROCESS | 2211 | Browser inner width/height: 1024/689 03:11:21 INFO - PROCESS | 2211 | __metrics 03:11:22 INFO - PROCESS | 2211 | 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" 03:11:22 ERROR - PROCESS | 2211 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:11:22 INFO - TEST-INFO | 2211: exit 0 03:11:22 INFO - Browser initialized. 03:11:22 INFO - Running cycle 1/1 for dromaeo_css test... 03:11:22 INFO - TEST-INFO | started process 2213 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpCFSgh_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:11:34 INFO - PROCESS | 2213 | RSS: Main: 211349504 03:11:34 INFO - PROCESS | 2213 | 03:12:26 INFO - PROCESS | 2213 | 1464430346680 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] 03:14:37 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55848/tests/dromaeo/cssquery-ext.html) 03:14:38 INFO - PROCESS | 2213 | RSS: Main: 352346112 03:14:38 INFO - PROCESS | 2213 | 03:17:41 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-ext.html (next: http://localhost:55848/tests/dromaeo/cssquery-jquery.html) 03:17:42 INFO - PROCESS | 2213 | RSS: Main: 361132032 03:17:42 INFO - PROCESS | 2213 | 03:20:44 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55848/tests/dromaeo/cssquery-mootools.html) 03:20:44 INFO - PROCESS | 2213 | RSS: Main: 364724224 03:20:44 INFO - PROCESS | 2213 | 03:23:47 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55848/tests/dromaeo/cssquery-prototype.html) 03:23:47 INFO - PROCESS | 2213 | RSS: Main: 362639360 03:23:47 INFO - PROCESS | 2213 | 03:26:51 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55848/tests/dromaeo/cssquery-yui.html) 03:26:51 INFO - PROCESS | 2213 | RSS: Main: 330928128 03:26:51 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:55848/tests/dromaeo/cssquery-yui.html (next: http://localhost:55848/tests/dromaeo/cssquery-dojo.html) 03:29:54 INFO - PROCESS | 2213 | RSS: Main: 369565696 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | __start_tp_report 03:29:54 INFO - PROCESS | 2213 | _x_x_mozilla_page_load 03:29:54 INFO - PROCESS | 2213 | _x_x_mozilla_page_load_details 03:29:54 INFO - PROCESS | 2213 | |i|pagename|runs| 03:29:54 INFO - PROCESS | 2213 | |0;dojo.html;3336.3660904428157;3413.897583072507;3420.418528850096;3437.9656203437958;3485.1984043669954;5573.3590637076695;5594.7202639868;5595.328560572737;5598.524125449331;5615.129654903486;5562.749676264568;5601.495865372116;5611.466910643492;5611.8316450506445;5639.915401268984;5083.517065878736;5133.435322114565;5164.967006598677;5212.400573933989;5263.26314315996;5218.869528261805;5239.5546378557865;5240.240165176047;5244.370675518943;5249.685018898859;5172.974135129319;5197.7401129943355;5216.217567364888;5221.921671174935;5222.738863056851;5218.530332270095;5246.265522826808;5248.475152484759;5255.369355677305;5303.7082960437165;5063.291139240509;5103.974480127576;5170.741462926839;5182.948170518284;5190.2993095932325;5469.808556700536;5491.203775452537;5504.532114770249;5535.778568857241;5578.944210557883;6182.103594978747;6184.288806787187;6209.6895155242055;6211.3167551569295;6215.906761398583;6101.389861013863;6216.222972128484;6226.564140510166;6248.218972628421;6263.404976527223;5664.433556644344;5687.544996400278;5717.799877004285;5759.1937128802;5763.6541807491685;6250.15622890913;6290.056491526216;6294.622322660655;6300.432961033529;6338.080978258126;5918.8816223675285;6007.369226231182;6094.634321940698;6104.664243466563;6129.325774164839;6117.279373342205;6197.8224137413745;6199.56603037783;6222.0355844844125;6222.439980401695;6122.357152498924;6146.293176284695;6148.1699970504305;6149.8155055347515;6155.4767844732805;6076.817695469143;6115.84710382244;6123.404636889863;6170.413810687977;6178.6601736904;4844.757762111951;4868.659193856396;4870.391201099863;4871.464138944713;4878.902421951612;4357.716748411343;4404.801783919731;4422.690411671152;4448.288273876165;4448.310511870665;5605.215269862141;5655;5683.829485115453;5718;5718.370979192286;6176.66028368444;6203.689815509207;6212.565120441525;6214.130021796952;6225.533085018642;5537.307836520435;5607.719614019283;5610.607257491936;5633.0987042073075;5643.1535269709875;5291.7354132294;5328.8401347959625;5375;5392.811251606175;5411.972940135274;6250.312465628777;6285.811425657238;6307.96846015767;6325.96837015812;6345.841353966188;6111.052786818051;6241.126242325987;6252;6254.655993920378;6271.6550589718;6267.4045965633195;6283.3088360281245;6284.968575157278;6291.96854015727;6298.748050077946;5086.237064440215;6077.726502307319;6150.815475535741;6152.446279834837;6163.260408750979;6161.661108639068;6197.442230199304;6208.906866397098;6235.282942461675;6255.593386429868;5690.516306113861;5844.415558444121;5911.201987731602;5960.04639257734;5983.431574000463;5896.027155519463;5952.642841429528;5990.550708696778;6031.668258245663;6049.032154855202;5806.82579522615;5806.883862322815;5815.5929084963645;5824.737886795019;5829.183914252093;5848.824535263949;5920.052791553503;5942.61373010753;5951.315598706031;5958.1658567801405;5792.362840087671;5822.970885145547;5847.356790753099;5856.238688970407;5868.853278667897 03:29:54 INFO - PROCESS | 2213 | |1;ext.html;482.2789929056061;516.7261351483712;516.8165301318032;537.1700722383916;554.750362336948;29777.851110744443;37988.67039653613;38849.2517836698;38987.61012389879;39012.02469938253;42208.78895605525;42732;42762.57237427625;42790.57209427905;42839.78580107095;8415;9420.764480887981;9631.229501639888;9645.083717046904;9652.469114198717;8500.574971251444;11572.884271157307;11779;11800.46897889597;11823.7635247295;11245.775084498306;11547.076233901309;11556.075513958864;11563.826542601868;11570;8545.145485451467;11419.885801142007;11508.827367589492;11531.365774882379;11540.192186546903;11452.484638191301;11578.536858525733;11584.594539191172;11595.536178552848;11622.883771162265;38940.02649933746;39295.4105688412;39376;39456.60543394558;39483.802580986914;3197.552342672028;3261.3314270574474;3267.722243609296;3286.277019055804;3307.2228026413773;22393.776062239333;23768.04927802886;27825.86087069572;28714.707838147333;28889.988850390346;2707.3502359433596;2732.699403065661;2753.9449211015667;2758.4069425073703;2765.25338158696;495094;497419;498295;498450;499034.50482747715;59598.404015959284;60888.69555652282;60985.695071525246;60996.69501652463;61199.38800612026;66047.33952660508;68864;70178.2982170182;70180.2981970184;70221.64889175522;17583.912080439517;17780.822191777916;17815.643687126183;17832.91083544574;17834.732479012826;12396.132270741142;12631.747365052648;13389.598312050653;13408.061435699603;13459.057865949486;16302.83697163037;19077.950712710935;19095.331663391713;19113.61772764537;19190.712139317922;7629.122650895107;7784.182660820532;7787.415943804237;7888.3689304856625;7896.526208427513;942.0908822985851;953.7520244736419;963.8987906269741;988.3723835364451;1077.3051381858656;23107.653385199235;27973;29370.265743356158;29653.555196672067;30316.24209394783;1082.9025387715144;1130.1580322659672;1162.8023236049892;1210.0984766349127;1229.4098832560421;15135.621609459631;15378.461753838787;15434.073955562702;15456.55747123554;15650.921745391428;645607;650932;654514;654891;655679;54478.455215447335;61088.694556526934;61164;61228.38771612316;61268.69365653233;1300.4603089717662;1349.939252733649;1350.2371160294401;1388.861133870237;1486.9628259293388;23384.532309353377;29911;29926.25184370408;30913;31458;45760.7711961438;56133.87732245414;57227;57418.42581574298;57489.42510574841;576850;580825;581620;581825;582067;24106;27095;27489.72510274872;28153.296167595974;28468.85765571159;44951.775241123585;45082.54917450915;46601.766991164826;46886.062278753554;46890.76554617342;36532;36941.63058369382;37808.62191378051;37820.24359512849;38502.6149738499;36933.63066369409;37252.25495490129;37263;37526.43710344292;38242.426363605126;36711.816440917624;37203.6279637211;37551.43672844964;37806;37821.243575128894;32815.95789221798;33104;33238.16904577405;33324.50013249851;33556;24573.38566535851;24639.137630183573;25010.499790004462;25174.370640734127;25389.492210155324 03:29:54 INFO - PROCESS | 2213 | |2;jquery.html;396.7996161938221;399.147819405569;399.43280541630884;401.50414238415544;404.9514058313003;19640.901795491;20091.29680461184;20757.16971321147;20757.688634670456;20785.896070519644;20423.59152816943;20476;20870;21247.15011399546;21366.145354185854;5837.066069428893;6001.069834175699;6155.045967874987;6162.815115546518;6201.131841542187;5994;6068.0291153415465;6099.695015249242;6103.084537319415;6115.205023346959;5816.1275808628625;6022;6042;6150.630962142263;6150.815475535741;6131.202943617323;6143.109249158847;6174.073888916653;6191.938080619181;6192.040233763774;5684.289463817023;5954.642721436728;5988.490978266853;5991.940080599182;5995.760169593211;13672.863271367258;13858.16850988944;13912.721745565032;21683.891580542153;25452.618210726854;2107.8629889057315;2184.595849767798;2188.627933251335;2214.878181700006;2504.799616030713;9970.601175952954;15418.614534636612;15504.612384690361;15717.21413929299;16242.837571624368;1623.1640705036916;1643.7534369844618;1651.1826645810281;1847.3349594146096;2011.0849563448596;122572;125541;127704;128951;133251;17130.828691712923;21684.34946951594;21752.673709894363;21913.452163695718;22082.558348833256;35716;35748;36452.81773591115;36652.26695466076;36687.899363019154;7180.7845764627145;8553.657853685907;8892.466452012899;8956.776080598038;8993.910060899307;8879.733607991771;8904.910950890408;8914.55427228636;8980.55097245135;9001.909980900238;4719.551642593948;4748.382710247645;4759.405074365704;4772.164871147535;4862.07620552094;1695.6947749405226;1929.7877233504303;1972.3294080012865;1995.6806910894188;1996.3112726109468;839.1901814748796;847.9533625650525;972.9513524323755;984.7931934293736;1001.8948010458943;17827.10864456798;20114.39656810298;20894.686579701316;20939.267125650527;21050.15799368008;940.3323640215524;951.4386511957978;986.0927946289431;993.2252842782641;995.7958618483193;10823.296485728402;11189;11474.738188504263;11691.76616467666;11755.706107347385;611498;618449;625136;627015;632865;133809;135925;136188;139855.30072349572;140495;1017.2574020964589;1026.5585798106886;1047.8113939490966;1050.9421981791074;1052.3685788526823;18398.596259692;18543.258269669062;18924.621507569496;18957.620847582697;19131.90434047821;56201.437985621254;56226.43773562376;56316.71841640765;59291.70354148201;59958;81840;82808.17191828004;83886;84216;84217.57891210505;7672.156062833195;7738.110117336579;8175.877361839696;8331.583420829056;8424.115467875785;10340.27618066728;12553.623391298275;12637.810432843331;12977.740445191232;12996.220226786423;10866.022058014816;11008.064314533034;11171.72070698239;11201.607943722256;11276.436178191221;10777.976092271221;10822.350658960488;10834.404107774148;10959.232853700165;10984.340939543654;11101.222914395914;11131.443427828739;11424.829937073075;11556;11583.942080289882;8150.633221504928;8288.58557072156;8299.460535065444;8329.416940814084;8363.581820908808;8478;10429.269951103654;10802.513886874953;11052.502637381498;11152.665420037412 03:29:54 INFO - PROCESS | 2213 | |3;mootools.html;1388.1948469887466;1501.7747337899314;1523.0785374848217;1546.4664690681711;1948.1915005272813;7717.845643087135;8309.210624990625;8479.830403391928;8491.957540212306;8492.235698787108;8157.714479993187;8378.99452065753;8395.118512556186;8398.49609023459;8408.83182336353;2124.808767210951;2178.0307763045453;2184.868907865525;2365.207655435431;2409.1929203716777;2388.0686532252435;2433.9513209735796;2438.4879175373194;2440.414300567869;2440.9145679901208;2305.6080466320764;2330.3125577954543;2335.1359996801207;2342.215357855112;2361.633946738258;2117.3224568138203;2284.6001949658744;2396.3769419950763;2398.2445529658103;2402.4594466244953;2357.9174728884577;2395.3652282145244;2395.7843794058617;2400.0519794681004;2401.3156250468533;8382.622781974826;8444.944381952257;8460.450070745383;8467.153284671545;8490.66037358505;4219.38818871262;5222.3472065991755;5225.608079394023;5250.68495890244;5255.710935898524;3367.6295607483157;3435.089701229176;3502.9299414011575;3529.7882127072457;3543.273628906094;1024.6311327921942;1038.8234000219904;1039.7140786283833;1065.280935368631;1075.9731006724817;11513.827292590619;11705.297682139098;11719.296842189497;11719.414029298501;11729.296242225493;3947.703922205789;4581.289900065495;4667.159911216014;4718.905621887543;4797.376341075638;9956.00879824038;9975.750606234902;9983.450910200008;9991.100800927952;10013.29906906524;5287.894242115136;5291;5331.226972089025;5340.118880384693;5340.172273297645;4345.587169218919;4448;4689.226277664216;4867.634927380461;4875.341828853059;3907.8632247871187;3936.7244292899795;3990.3016972029777;4016.979915100406;4021.8994525136522;1291.3930452687223;1309.3845892430543;1310.4496111633136;1325.5360623781792;1326.303690562443;875.872998415239;897.8249241397996;900.8963969143501;916.1983264643437;917.8439665256923;5608.551315894719;5634.239377684042;5772.9243264007055;5808.912866307009;5939.732712027969;970.262600423669;983.3460748602242;983.5623147699348;997.6159178716247;1027.5581499955092;3771.358868992223;3806.238752249561;3859.9614003860165;3943.7633741975574;3953.525576930787;9749.317547771589;10594.735131621617;10597.9470102649;10707.785844283226;10724.73188170302;10253.74365640865;10451.581936722447;10565.418901960466;10610.681679549625;10614.681559553226;7884.763457096296;8227.670893164206;8236.876446853303;8241.134680858544;8282.4202305838;5478.479544443431;5495.038368285614;5639.333180909961;5730.111832665943;5737.196792448977;7591.8861217082895;9100.635974560944;9391.85912211331;9566.712998610054;9569.61721531131;9437.716868493957;9722.368046076983;9919.702408927744;9945.154661853823;9959.950200248952;6355.110284560258;6444.742210311535;6448.1295025171;6729.427998620172;6759.5606285591275;7405.2224516425;7462.253774622494;7473.215312392121;7829.29536341732;7953.363730901629;7015.438764898694;7093.751718689821;7100.609466479394;7210.603416812126;7225.096862892138;7031.050808140835;7059.223485416703;7087.184973728087;7140.7857764267155;7155.785326440215;6666.933330666825;6733.956236783307;6741.696623651849;6760;6789.321067893171;4762.928556071731;4853.199222128309;4870.488598697086;4949.430815456125;5034.169362055366;4925.507449255046;4982.227754698028;4986.401631804207;5061.974690126526;5205.427402985744 03:29:54 INFO - PROCESS | 2213 | |4;prototype.html;271.33387533605;278.8549954023909;296.1308559378225;328.6795374509853;336.81811821616327;5433.320834895638;6071.028635418334;6386.552941294111;6415.45468635166;6442.065900444439;5669.09294512878;5759.395263497336;5974;6449.838754031151;6453.709583068761;4034.253663072341;4692.085043416535;4781.737004464753;4816.542428469307;4825.2279635258365;4819.493953134923;4896.143174944371;4896.55930966213;4897.534734200245;4898.14282500561;4838.395200599925;4840.128776820171;4840.612750979931;4844.491328410519;4848.078865015654;4568.497465278817;4653.930191047137;4754.904901901978;4767.523247675239;4769.737664428455;3349.330133973215;3803.3835619861447;4064.8983775405554;4075.266452038632;4096.283150448659;5545.833624991256;6240.750369985195;6253.6247825130185;6264.809686159615;6274.905876411857;5615.9157612635845;5641.323041235078;5643.435656434365;5663.461971112738;5675.5459563234845;6893.20728116263;6899.551529150639;6906.965465172692;6916.723331066752;6935.237123916365;4305.418768466248;4458.866234012985;4488.685791994561;4509.819607215707;4516.435445569304;7191.065161528968;7287.453440991947;7317.707291708325;7338.77983660491;7341.853162936712;4564.817407303737;4587.610053145453;4590.357349971006;4603.056373443436;4609.746463944515;4637.9536204637525;4641.837535686234;4641.930371044437;4643;4653.53464653532;5500.202470641814;5521.861953451196;5536.335639723259;5542.722863856791;5543.722813859291;5652.802151924745;6664.5334826561675;6918.723251070001;6937.479689023344;6995.650217489207;1597.225345707331;1597.9201039947957;1614.3784642912328;1621.9270132844051;1622.4483675550134;959.5681943125621;971.155095067296;1012.7569383347943;1014.3862962907482;1040.8802987656366;6489;6602.240742314695;6604.405603495708;6613.140291762136;6621.437177839937;7274.96362518184;7287.161976372785;7294.051773269441;7308.451866110063;7309.561426314438;6579.276279609239;6579.901301480481;6583.210014798255;6595.934040659532;6603.108580341688;3784.2942296257756;3842.9807850960565;3845.788481633481;3851.614838516182;3857.7685338879755;6130.846728831726;6199.876002480015;7381.81545461357;7636.236376362319;7751.573663448452;6334.018227174795;7360.889586656204;7491.325780679765;7508.3993280537425;7536.6985320587655;7645.4648174628355;7668.463207575416;7670.271324224302;7672.309492145622;7674.6930122794465;6963.756268530779;7271.236520165518;7279.854402911807;7311.159216689936;7318.780436586911;7072.434205263669;7077.6461176941975;7107.466939979418;7135.571865687868;7174.856502870018;7688.577128257776;7701.499402538817;7730.652120654697;7736.381089512938;7739.110002349802;4579.496255411968;4627.745473998963;4639.118567472305;4645.930311045269;4649.628029757679;5860.238169037998;5913.970430147821;7035.753748618774;7262.128544574685;7275.163356214103;6837.829054273484;6979.1973922999505;6993.930060699327;7019.052427922369;7024.438044956289;6912.101426814482;6967.930320696728;6971.965140174267;6993;7009.088818453773;6833.4191593715095;6835.111435513351;6840.623765692934;6851.691673874588;6856.142982127234;6731.394174524316;6923.342282483156;6945.236024037251;6969.512134150561;6975.6163411012885;5347.358317001985;6505.8698826024165;6722.193336799615;6741.764038258638;6875.7937261882225 03:29:54 INFO - PROCESS | 2213 | |5;yui.html;694.9166100067991;754.5585832288111;763.7975936376861;772.1892013385947;783.2441693765516;14861.40554377825;14967.101973881574;15250;15292;15299.92350038251;13666.38501267443;13878.861211387908;13890.930545347284;13899.374528146232;13917.652058698537;751.9285667861544;758.0675768804374;905.180811365715;933.9206167475772;935.4480856294784;909.8817153770001;935.3218916285673;939.6523286384047;940.7036783413203;942.2226663003015;954.9856752148723;966.2704657983213;969.3747532841348;974.0794948773412;975.624384611926;782.2842099478999;952.2239374909427;962.8122516109362;966.0387914025555;978.6281213138961;710.3819676881095;720.0063911801733;724.8187953011748;724.8695234857723;725.1117381207978;13638.567950365168;13861.861381386158;15111.54665360041;15125.470608528649;15150;12613.558525451657;12845.871541284561;12875.935620321838;12932.48270069196;12937.741245175044;9356.204722598588;9429.952850235704;9472.621095156186;10107;10434.321769084985;3460.325236578868;3539.716822654207;3600.873969411058;3615.132368231632;3625.474306225609;85858.28283434358;85985;88527.11472885318;88922.77693057661;88972;9408.200302974186;9462.195713364303;9616.807663846685;9673.419594824332;9800.55897484615;21101.577968440546;21382.78617213808;21399;21438.24966126178;22919.885400573225;7525.924740752632;7529.171791102975;7707.922920770833;7807.80480487981;7917.564533950688;4139.130782535641;4199.4330765346895;4211.642010429148;4284.271673815459;4300.569943005675;3643.3441980443245;3708.147126160998;3708.9258214835554;3712.7401081924004;3717.237966216919;631.0975305313445;638.9041643753474;641.6791604197902;653.4543656047254;661.024988142486;546.2625455634941;593.5631157100638;594.3610618584952;613.2457077794338;620.7610070123035;7739.883901741478;8170.305524030405;8281.21328473794;8283.875741863876;8399;495.752123938031;531.7288183026607;574.4686165297084;582.6911736779514;614.2904944788776;3616.855325786939;3834.616538346143;3925.6961002424437;3931.253061918226;4036.616521430459;90664.54667726619;94087.58868616977;94203.52898235465;94295;94302.52848735849;71384.64307678428;74640.88038679425;74858.6257068722;75510.62244688741;76369;70350.64824675844;70386;70441;71203.64398177977;71702.2829771706;7111.9288807113335;7633.351165151024;7764.805879853049;7899.09160446533;7948.523088614702;44085.67742967716;48156.759216203696;48282;48332;48575.27137093017;62921.05618415819;65982;66936;67012;68629.31370686229;10086.798264034825;10274.743131421774;10284.640037598649;10308.226882983656;10382.961703829858;11026.117910566974;11112.444377781241;11186.776264474827;11492;11570.88429115698;10010.499475026365;10295.897041029493;10428.113610343205;10604.946975265075;10634.41510716918;10402.58389664405;10456.954304569786;10576.629817956335;10721.571137154739;10972.561097556008;10049.346792458467;10120.949395252977;10152.746181345525;10227.488625568543;10779.407132607781;8838.734837954871;9063.546822658709;9079.636814527345;9090.590923408594;9297.209737172145;8657.95671021641;8713.607887645197;8800.779980500538;8917.554122293988;9446.95276523613 03:29:54 INFO - PROCESS | 2213 | __end_tp_report 03:29:54 INFO - PROCESS | 2213 | __start_cc_report 03:29:54 INFO - PROCESS | 2213 | _x_x_mozilla_cycle_collect,1284 03:29:54 INFO - PROCESS | 2213 | __end_cc_report 03:29:54 INFO - PROCESS | 2213 | __startTimestamp1464431394680__endTimestamp 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | ------- Summary: start ------- 03:29:54 INFO - PROCESS | 2213 | Number of tests: 6 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#0] dojo.html Cycles:165 Average:5685.43 Median:5824.74 stddev:618.81 (10.6%) stddev-sans-first:592.64 03:29:54 INFO - PROCESS | 2213 | Values: 3336.4 3413.9 3420.4 3438.0 3485.2 5573.4 5594.7 5595.3 5598.5 5615.1 5562.7 5601.5 5611.5 5611.8 5639.9 5083.5 5133.4 5165.0 5212.4 5263.3 5218.9 5239.6 5240.2 5244.4 5249.7 5173.0 5197.7 5216.2 5221.9 5222.7 5218.5 5246.3 5248.5 5255.4 5303.7 5063.3 5104.0 5170.7 5182.9 5190.3 5469.8 5491.2 5504.5 5535.8 5578.9 6182.1 6184.3 6209.7 6211.3 6215.9 6101.4 6216.2 6226.6 6248.2 6263.4 5664.4 5687.5 5717.8 5759.2 5763.7 6250.2 6290.1 6294.6 6300.4 6338.1 5918.9 6007.4 6094.6 6104.7 6129.3 6117.3 6197.8 6199.6 6222.0 6222.4 6122.4 6146.3 6148.2 6149.8 6155.5 6076.8 6115.8 6123.4 6170.4 6178.7 4844.8 4868.7 4870.4 4871.5 4878.9 4357.7 4404.8 4422.7 4448.3 4448.3 5605.2 5655.0 5683.8 5718.0 5718.4 6176.7 6203.7 6212.6 6214.1 6225.5 5537.3 5607.7 5610.6 5633.1 5643.2 5291.7 5328.8 5375.0 5392.8 5412.0 6250.3 6285.8 6308.0 6326.0 6345.8 6111.1 6241.1 6252.0 6254.7 6271.7 6267.4 6283.3 6285.0 6292.0 6298.7 5086.2 6077.7 6150.8 6152.4 6163.3 6161.7 6197.4 6208.9 6235.3 6255.6 5690.5 5844.4 5911.2 5960.0 5983.4 5896.0 5952.6 5990.6 6031.7 6049.0 5806.8 5806.9 5815.6 5824.7 5829.2 5848.8 5920.1 5942.6 5951.3 5958.2 5792.4 5823.0 5847.4 5856.2 5868.9 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#1] ext.html Cycles:180 Average:71063.42 Median:36621.91 stddev:155105.96 (423.5%) stddev-sans-first:155450.55 03:29:54 INFO - PROCESS | 2213 | Values: 482.3 516.7 516.8 537.2 554.8 29777.9 37988.7 38849.3 38987.6 39012.0 42208.8 42732.0 42762.6 42790.6 42839.8 8415.0 9420.8 9631.2 9645.1 9652.5 8500.6 11572.9 11779.0 11800.5 11823.8 11245.8 11547.1 11556.1 11563.8 11570.0 8545.1 11419.9 11508.8 11531.4 11540.2 11452.5 11578.5 11584.6 11595.5 11622.9 38940.0 39295.4 39376.0 39456.6 39483.8 3197.6 3261.3 3267.7 3286.3 3307.2 22393.8 23768.0 27825.9 28714.7 28890.0 2707.4 2732.7 2753.9 2758.4 2765.3 495094.0 497419.0 498295.0 498450.0 499034.5 59598.4 60888.7 60985.7 60996.7 61199.4 66047.3 68864.0 70178.3 70180.3 70221.6 17583.9 17780.8 17815.6 17832.9 17834.7 12396.1 12631.7 13389.6 13408.1 13459.1 16302.8 19078.0 19095.3 19113.6 19190.7 7629.1 7784.2 7787.4 7888.4 7896.5 942.1 953.8 963.9 988.4 1077.3 23107.7 27973.0 29370.3 29653.6 30316.2 1082.9 1130.2 1162.8 1210.1 1229.4 15135.6 15378.5 15434.1 15456.6 15650.9 645607.0 650932.0 654514.0 654891.0 655679.0 54478.5 61088.7 61164.0 61228.4 61268.7 1300.5 1349.9 1350.2 1388.9 1487.0 23384.5 29911.0 29926.3 30913.0 31458.0 45760.8 56133.9 57227.0 57418.4 57489.4 576850.0 580825.0 581620.0 581825.0 582067.0 24106.0 27095.0 27489.7 28153.3 28468.9 44951.8 45082.5 46601.8 46886.1 46890.8 36532.0 36941.6 37808.6 37820.2 38502.6 36933.6 37252.3 37263.0 37526.4 38242.4 36711.8 37203.6 37551.4 37806.0 37821.2 32816.0 33104.0 33238.2 33324.5 33556.0 24573.4 24639.1 25010.5 25174.4 25389.5 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#2] jquery.html Cycles:180 Average:37334.79 Median:12148.72 stddev:104186.76 (857.6%) stddev-sans-first:104442.12 03:29:54 INFO - PROCESS | 2213 | Values: 396.8 399.1 399.4 401.5 405.0 19640.9 20091.3 20757.2 20757.7 20785.9 20423.6 20476.0 20870.0 21247.2 21366.1 5837.1 6001.1 6155.0 6162.8 6201.1 5994.0 6068.0 6099.7 6103.1 6115.2 5816.1 6022.0 6042.0 6150.6 6150.8 6131.2 6143.1 6174.1 6191.9 6192.0 5684.3 5954.6 5988.5 5991.9 5995.8 13672.9 13858.2 13912.7 21683.9 25452.6 2107.9 2184.6 2188.6 2214.9 2504.8 9970.6 15418.6 15504.6 15717.2 16242.8 1623.2 1643.8 1651.2 1847.3 2011.1 122572.0 125541.0 127704.0 128951.0 133251.0 17130.8 21684.3 21752.7 21913.5 22082.6 35716.0 35748.0 36452.8 36652.3 36687.9 7180.8 8553.7 8892.5 8956.8 8993.9 8879.7 8904.9 8914.6 8980.6 9001.9 4719.6 4748.4 4759.4 4772.2 4862.1 1695.7 1929.8 1972.3 1995.7 1996.3 839.2 848.0 973.0 984.8 1001.9 17827.1 20114.4 20894.7 20939.3 21050.2 940.3 951.4 986.1 993.2 995.8 10823.3 11189.0 11474.7 11691.8 11755.7 611498.0 618449.0 625136.0 627015.0 632865.0 133809.0 135925.0 136188.0 139855.3 140495.0 1017.3 1026.6 1047.8 1050.9 1052.4 18398.6 18543.3 18924.6 18957.6 19131.9 56201.4 56226.4 56316.7 59291.7 59958.0 81840.0 82808.2 83886.0 84216.0 84217.6 7672.2 7738.1 8175.9 8331.6 8424.1 10340.3 12553.6 12637.8 12977.7 12996.2 10866.0 11008.1 11171.7 11201.6 11276.4 10778.0 10822.4 10834.4 10959.2 10984.3 11101.2 11131.4 11424.8 11556.0 11583.9 8150.6 8288.6 8299.5 8329.4 8363.6 8478.0 10429.3 10802.5 11052.5 11152.7 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#3] mootools.html Cycles:180 Average:5544.36 Median:5133.70 stddev:3082.14 (60.0%) stddev-sans-first:3074.96 03:29:54 INFO - PROCESS | 2213 | Values: 1388.2 1501.8 1523.1 1546.5 1948.2 7717.8 8309.2 8479.8 8492.0 8492.2 8157.7 8379.0 8395.1 8398.5 8408.8 2124.8 2178.0 2184.9 2365.2 2409.2 2388.1 2434.0 2438.5 2440.4 2440.9 2305.6 2330.3 2335.1 2342.2 2361.6 2117.3 2284.6 2396.4 2398.2 2402.5 2357.9 2395.4 2395.8 2400.1 2401.3 8382.6 8444.9 8460.5 8467.2 8490.7 4219.4 5222.3 5225.6 5250.7 5255.7 3367.6 3435.1 3502.9 3529.8 3543.3 1024.6 1038.8 1039.7 1065.3 1076.0 11513.8 11705.3 11719.3 11719.4 11729.3 3947.7 4581.3 4667.2 4718.9 4797.4 9956.0 9975.8 9983.5 9991.1 10013.3 5287.9 5291.0 5331.2 5340.1 5340.2 4345.6 4448.0 4689.2 4867.6 4875.3 3907.9 3936.7 3990.3 4017.0 4021.9 1291.4 1309.4 1310.4 1325.5 1326.3 875.9 897.8 900.9 916.2 917.8 5608.6 5634.2 5772.9 5808.9 5939.7 970.3 983.3 983.6 997.6 1027.6 3771.4 3806.2 3860.0 3943.8 3953.5 9749.3 10594.7 10597.9 10707.8 10724.7 10253.7 10451.6 10565.4 10610.7 10614.7 7884.8 8227.7 8236.9 8241.1 8282.4 5478.5 5495.0 5639.3 5730.1 5737.2 7591.9 9100.6 9391.9 9566.7 9569.6 9437.7 9722.4 9919.7 9945.2 9960.0 6355.1 6444.7 6448.1 6729.4 6759.6 7405.2 7462.3 7473.2 7829.3 7953.4 7015.4 7093.8 7100.6 7210.6 7225.1 7031.1 7059.2 7087.2 7140.8 7155.8 6666.9 6734.0 6741.7 6760.0 6789.3 4762.9 4853.2 4870.5 4949.4 5034.2 4925.5 4982.2 4986.4 5062.0 5205.4 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#4] prototype.html Cycles:180 Average:5655.89 Median:6401.00 stddev:1822.85 (28.5%) stddev-sans-first:1782.60 03:29:54 INFO - PROCESS | 2213 | Values: 271.3 278.9 296.1 328.7 336.8 5433.3 6071.0 6386.6 6415.5 6442.1 5669.1 5759.4 5974.0 6449.8 6453.7 4034.3 4692.1 4781.7 4816.5 4825.2 4819.5 4896.1 4896.6 4897.5 4898.1 4838.4 4840.1 4840.6 4844.5 4848.1 4568.5 4653.9 4754.9 4767.5 4769.7 3349.3 3803.4 4064.9 4075.3 4096.3 5545.8 6240.8 6253.6 6264.8 6274.9 5615.9 5641.3 5643.4 5663.5 5675.5 6893.2 6899.6 6907.0 6916.7 6935.2 4305.4 4458.9 4488.7 4509.8 4516.4 7191.1 7287.5 7317.7 7338.8 7341.9 4564.8 4587.6 4590.4 4603.1 4609.7 4638.0 4641.8 4641.9 4643.0 4653.5 5500.2 5521.9 5536.3 5542.7 5543.7 5652.8 6664.5 6918.7 6937.5 6995.7 1597.2 1597.9 1614.4 1621.9 1622.4 959.6 971.2 1012.8 1014.4 1040.9 6489.0 6602.2 6604.4 6613.1 6621.4 7275.0 7287.2 7294.1 7308.5 7309.6 6579.3 6579.9 6583.2 6595.9 6603.1 3784.3 3843.0 3845.8 3851.6 3857.8 6130.8 6199.9 7381.8 7636.2 7751.6 6334.0 7360.9 7491.3 7508.4 7536.7 7645.5 7668.5 7670.3 7672.3 7674.7 6963.8 7271.2 7279.9 7311.2 7318.8 7072.4 7077.6 7107.5 7135.6 7174.9 7688.6 7701.5 7730.7 7736.4 7739.1 4579.5 4627.7 4639.1 4645.9 4649.6 5860.2 5914.0 7035.8 7262.1 7275.2 6837.8 6979.2 6993.9 7019.1 7024.4 6912.1 6967.9 6972.0 6993.0 7009.1 6833.4 6835.1 6840.6 6851.7 6856.1 6731.4 6923.3 6945.2 6969.5 6975.6 5347.4 6505.9 6722.2 6741.8 6875.8 03:29:54 INFO - PROCESS | 2213 | 03:29:54 INFO - PROCESS | 2213 | [#5] yui.html Cycles:180 Average:18177.77 Median:647.57 stddev:25996.87 (4014.5%) stddev-sans-first:26036.66 03:29:54 INFO - PROCESS | 2213 | Values: 694.9 754.6 763.8 772.2 783.2 14861.4 14967.1 15250.0 15292.0 15299.9 13666.4 13878.9 13890.9 13899.4 13917.7 751.9 758.1 905.2 933.9 935.4 909.9 935.3 939.7 940.7 942.2 955.0 966.3 969.4 974.1 975.6 782.3 952.2 962.8 966.0 978.6 710.4 720.0 724.8 724.9 725.1 13638.6 13861.9 15111.5 15125.5 15150.0 12613.6 12845.9 12875.9 12932.5 12937.7 9356.2 9430.0 9472.6 10107.0 10434.3 3460.3 3539.7 3600.9 3615.1 3625.5 85858.3 85985.0 88527.1 88922.8 88972.0 9408.2 9462.2 9616.8 9673.4 9800.6 21101.6 21382.8 21399.0 21438.2 22919.9 7525.9 7529.2 7707.9 7807.8 7917.6 4139.1 4199.4 4211.6 4284.3 4300.6 3643.3 3708.1 3708.9 3712.7 3717.2 631.1 638.9 641.7 653.5 661.0 546.3 593.6 594.4 613.2 620.8 7739.9 8170.3 8281.2 8283.9 8399.0 495.8 531.7 574.5 582.7 614.3 3616.9 3834.6 3925.7 3931.3 4036.6 90664.5 94087.6 94203.5 94295.0 94302.5 71384.6 74640.9 74858.6 75510.6 76369.0 70350.6 70386.0 70441.0 71203.6 71702.3 7111.9 7633.4 7764.8 7899.1 7948.5 44085.7 48156.8 48282.0 48332.0 48575.3 62921.1 65982.0 66936.0 67012.0 68629.3 10086.8 10274.7 10284.6 10308.2 10383.0 11026.1 11112.4 11186.8 11492.0 11570.9 10010.5 10295.9 10428.1 10604.9 10634.4 10402.6 10457.0 10576.6 10721.6 10972.6 10049.3 10120.9 10152.7 10227.5 10779.4 8838.7 9063.5 9079.6 9090.6 9297.2 8658.0 8713.6 8800.8 8917.6 9447.0 03:29:54 INFO - PROCESS | 2213 | -------- Summary: end -------- 03:29:54 INFO - PROCESS | 2213 | 03:29:55 INFO - TEST-INFO | 2213: exit 0 03:29:55 INFO - TEST-OK | dromaeo_css | took 1115464ms 03:29:55 INFO - TEST-START | kraken 03:29:55 INFO - operating with platform_type : mac_ 03:29:55 INFO - Initialising browser for kraken test... 03:29:55 INFO - TEST-INFO | started process 2218 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpZt71nP/profile http://localhost:55848/getInfo.html) 03:29:57 INFO - PROCESS | 2218 | __metrics Screen width/height:1600/1200 03:29:57 INFO - PROCESS | 2218 | colorDepth:24 03:29:57 INFO - PROCESS | 2218 | Browser inner width/height: 1024/689 03:29:57 INFO - PROCESS | 2218 | __metrics 03:29:57 INFO - PROCESS | 2218 | 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" 03:29:57 ERROR - PROCESS | 2218 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:29:58 INFO - TEST-INFO | 2218: exit 0 03:29:58 INFO - Browser initialized. 03:29:58 INFO - Running cycle 1/1 for kraken test... 03:29:58 INFO - TEST-INFO | started process 2220 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpZt71nP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:10 INFO - PROCESS | 2220 | RSS: Main: 211365888 03:30:10 INFO - PROCESS | 2220 | 03:30:12 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-beat-detection) 03:30:13 INFO - PROCESS | 2220 | RSS: Main: 271745024 03:30:13 INFO - PROCESS | 2220 | 03:30:16 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-dft) 03:30:16 INFO - PROCESS | 2220 | RSS: Main: 285900800 03:30:16 INFO - PROCESS | 2220 | 03:30:20 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-fft) 03:30:20 INFO - PROCESS | 2220 | RSS: Main: 375709696 03:30:20 INFO - PROCESS | 2220 | 03:30:22 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55848/tests/kraken/driver.html?testName=audio-oscillator) 03:30:23 INFO - PROCESS | 2220 | RSS: Main: 278827008 03:30:23 INFO - PROCESS | 2220 | 03:30:25 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:30:26 INFO - PROCESS | 2220 | RSS: Main: 279814144 03:30:26 INFO - PROCESS | 2220 | 03:30:29 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-darkroom) 03:30:29 INFO - PROCESS | 2220 | RSS: Main: 316960768 03:30:29 INFO - PROCESS | 2220 | 03:30:32 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55848/tests/kraken/driver.html?testName=imaging-desaturate) 03:30:33 INFO - PROCESS | 2220 | RSS: Main: 318550016 03:30:33 INFO - PROCESS | 2220 | 03:30:36 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55848/tests/kraken/driver.html?testName=json-parse-financial) 03:30:36 INFO - PROCESS | 2220 | RSS: Main: 317861888 03:30:36 INFO - PROCESS | 2220 | 03:30:38 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55848/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:30:38 INFO - PROCESS | 2220 | RSS: Main: 309436416 03:30:38 INFO - PROCESS | 2220 | 03:30:41 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:30:41 INFO - PROCESS | 2220 | RSS: Main: 315592704 03:30:41 INFO - PROCESS | 2220 | 03:30:43 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:30:44 INFO - PROCESS | 2220 | RSS: Main: 318771200 03:30:44 INFO - PROCESS | 2220 | 03:30:47 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:30:47 INFO - PROCESS | 2220 | RSS: Main: 324071424 03:30:47 INFO - PROCESS | 2220 | 03:30:50 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:30:51 INFO - PROCESS | 2220 | RSS: Main: 322105344 03:30:51 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | Cycle 1(1): loaded http://localhost:55848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55848/tests/kraken/driver.html?testName=ai-astar) 03:30:53 INFO - PROCESS | 2220 | RSS: Main: 386875392 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | __start_tp_report 03:30:53 INFO - PROCESS | 2220 | _x_x_mozilla_page_load 03:30:53 INFO - PROCESS | 2220 | _x_x_mozilla_page_load_details 03:30:53 INFO - PROCESS | 2220 | |i|pagename|runs| 03:30:53 INFO - PROCESS | 2220 | |0;ai-astar;114;112;119;110;109;110;113;111;112;133 03:30:53 INFO - PROCESS | 2220 | |1;audio-beat-detection;158;121;158;158;119;142;120;152;162;119 03:30:53 INFO - PROCESS | 2220 | |2;audio-dft;182;198;262;203;185;184;182;181;184;184 03:30:53 INFO - PROCESS | 2220 | |3;audio-fft;72;78;82;183;84;82;57;65;132;55 03:30:53 INFO - PROCESS | 2220 | |4;audio-oscillator;89;91;138;77;76;86;123;79;80;125 03:30:53 INFO - PROCESS | 2220 | |5;imaging-gaussian-blur;87;96;135;85;90;122;88;84;86;85 03:30:53 INFO - PROCESS | 2220 | |6;imaging-darkroom;92;93;93;92;111;119;92;92;92;94 03:30:53 INFO - PROCESS | 2220 | |7;imaging-desaturate;84;84;82;83;83;83;90;84;83;84 03:30:53 INFO - PROCESS | 2220 | |8;json-parse-financial;59;62;64;63;63;65;65;80;60;60 03:30:53 INFO - PROCESS | 2220 | |9;json-stringify-tinderbox;65;65;77;105;99;59;89;91;99;77 03:30:53 INFO - PROCESS | 2220 | |10;stanford-crypto-aes;82;82;81;81;77;79;80;80;82;86 03:30:53 INFO - PROCESS | 2220 | |11;stanford-crypto-ccm;130;129;128;139;145;144;120;126;147;134 03:30:53 INFO - PROCESS | 2220 | |12;stanford-crypto-pbkdf2;166;167;167;164;166;164;164;174;165;169 03:30:53 INFO - PROCESS | 2220 | |13;stanford-crypto-sha256-iterative;70;67;68;67;68;66;67;71;66;66 03:30:53 INFO - PROCESS | 2220 | __end_tp_report 03:30:53 INFO - PROCESS | 2220 | __start_cc_report 03:30:53 INFO - PROCESS | 2220 | _x_x_mozilla_cycle_collect,2008 03:30:53 INFO - PROCESS | 2220 | __end_cc_report 03:30:53 INFO - PROCESS | 2220 | __startTimestamp1464431453324__endTimestamp 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | ------- Summary: start ------- 03:30:53 INFO - PROCESS | 2220 | Number of tests: 14 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#0] ai-astar Cycles:10 Average:114.30 Median:112.50 stddev:7.15 (6.4%) stddev-sans-first:7.58 03:30:53 INFO - PROCESS | 2220 | Values: 114.0 112.0 119.0 110.0 109.0 110.0 113.0 111.0 112.0 133.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:155.00 stddev:18.97 (12.2%) stddev-sans-first:19.09 03:30:53 INFO - PROCESS | 2220 | Values: 158.0 121.0 158.0 158.0 119.0 142.0 120.0 152.0 162.0 119.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#2] audio-dft Cycles:10 Average:194.50 Median:184.50 stddev:24.85 (13.5%) stddev-sans-first:25.94 03:30:53 INFO - PROCESS | 2220 | Values: 182.0 198.0 262.0 203.0 185.0 184.0 182.0 181.0 184.0 184.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#3] audio-fft Cycles:10 Average:89.00 Median:75.00 stddev:39.40 (52.5%) stddev-sans-first:41.31 03:30:53 INFO - PROCESS | 2220 | Values: 72.0 78.0 82.0 183.0 84.0 82.0 57.0 65.0 132.0 55.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:79.50 stddev:23.12 (29.1%) stddev-sans-first:24.37 03:30:53 INFO - PROCESS | 2220 | Values: 89.0 91.0 138.0 77.0 76.0 86.0 123.0 79.0 80.0 125.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:86.50 stddev:17.84 (20.6%) stddev-sans-first:18.63 03:30:53 INFO - PROCESS | 2220 | Values: 87.0 96.0 135.0 85.0 90.0 122.0 88.0 84.0 86.0 85.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:92.00 stddev:9.70 (10.5%) stddev-sans-first:10.11 03:30:53 INFO - PROCESS | 2220 | Values: 92.0 93.0 93.0 92.0 111.0 119.0 92.0 92.0 92.0 94.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:2.21 (2.6%) stddev-sans-first:2.35 03:30:53 INFO - PROCESS | 2220 | Values: 84.0 84.0 82.0 83.0 83.0 83.0 90.0 84.0 83.0 84.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:63.50 stddev:5.97 (9.4%) stddev-sans-first:6.04 03:30:53 INFO - PROCESS | 2220 | Values: 59.0 62.0 64.0 63.0 63.0 65.0 65.0 80.0 60.0 60.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#9] json-stringify-tinderbox Cycles:10 Average:82.60 Median:83.00 stddev:16.30 (19.6%) stddev-sans-first:15.99 03:30:53 INFO - PROCESS | 2220 | Values: 65.0 65.0 77.0 105.0 99.0 59.0 89.0 91.0 99.0 77.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.50 stddev:2.36 (2.9%) stddev-sans-first:2.47 03:30:53 INFO - PROCESS | 2220 | Values: 82.0 82.0 81.0 81.0 77.0 79.0 80.0 80.0 82.0 86.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#11] stanford-crypto-ccm Cycles:10 Average:134.20 Median:136.50 stddev:9.14 (6.7%) stddev-sans-first:9.57 03:30:53 INFO - PROCESS | 2220 | Values: 130.0 129.0 128.0 139.0 145.0 144.0 120.0 126.0 147.0 134.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:166.50 stddev:3.06 (1.8%) stddev-sans-first:3.24 03:30:53 INFO - PROCESS | 2220 | Values: 166.0 167.0 167.0 164.0 166.0 164.0 164.0 174.0 165.0 169.0 03:30:53 INFO - PROCESS | 2220 | 03:30:53 INFO - PROCESS | 2220 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:1.71 (2.5%) stddev-sans-first:1.58 03:30:53 INFO - PROCESS | 2220 | Values: 70.0 67.0 68.0 67.0 68.0 66.0 67.0 71.0 66.0 66.0 03:30:53 INFO - PROCESS | 2220 | -------- Summary: end -------- 03:30:53 INFO - PROCESS | 2220 | 03:30:54 INFO - TEST-INFO | 2220: exit 0 03:30:54 INFO - TEST-OK | kraken | took 58852ms 03:30:54 INFO - SUITE-END | took 1174s 03:30:54 INFO - Completed test suite (00:19:34) 03:30:54 INFO - javascript benchmark 03:30:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.3660904428157, 3413.897583072507, 3420.418528850096, 3437.9656203437958, 3485.1984043669954, 5573.3590637076695, 5594.7202639868, 5595.328560572737, 5598.524125449331, 5615.129654903486, 5562.749676264568, 5601.495865372116, 5611.466910643492, 5611.8316450506445, 5639.915401268984, 5083.517065878736, 5133.435322114565, 5164.967006598677, 5212.400573933989, 5263.26314315996, 5218.869528261805, 5239.5546378557865, 5240.240165176047, 5244.370675518943, 5249.685018898859, 5172.974135129319, 5197.7401129943355, 5216.217567364888, 5221.921671174935, 5222.738863056851, 5218.530332270095, 5246.265522826808, 5248.475152484759, 5255.369355677305, 5303.7082960437165, 5063.291139240509, 5103.974480127576, 5170.741462926839, 5182.948170518284, 5190.2993095932325, 5469.808556700536, 5491.203775452537, 5504.532114770249, 5535.778568857241, 5578.944210557883, 6182.103594978747, 6184.288806787187, 6209.6895155242055, 6211.3167551569295, 6215.906761398583, 6101.389861013863, 6216.222972128484, 6226.564140510166, 6248.218972628421, 6263.404976527223, 5664.433556644344, 5687.544996400278, 5717.799877004285, 5759.1937128802, 5763.6541807491685, 6250.15622890913, 6290.056491526216, 6294.622322660655, 6300.432961033529, 6338.080978258126, 5918.8816223675285, 6007.369226231182, 6094.634321940698, 6104.664243466563, 6129.325774164839, 6117.279373342205, 6197.8224137413745, 6199.56603037783, 6222.0355844844125, 6222.439980401695, 6122.357152498924, 6146.293176284695, 6148.1699970504305, 6149.8155055347515, 6155.4767844732805, 6076.817695469143, 6115.84710382244, 6123.404636889863, 6170.413810687977, 6178.6601736904, 4844.757762111951, 4868.659193856396, 4870.391201099863, 4871.464138944713, 4878.902421951612, 4357.716748411343, 4404.801783919731, 4422.690411671152, 4448.288273876165, 4448.310511870665, 5605.215269862141, 5655.0, 5683.829485115453, 5718.0, 5718.370979192286, 6176.66028368444, 6203.689815509207, 6212.565120441525, 6214.130021796952, 6225.533085018642, 5537.307836520435, 5607.719614019283, 5610.607257491936, 5633.0987042073075, 5643.1535269709875, 5291.7354132294, 5328.8401347959625, 5375.0, 5392.811251606175, 5411.972940135274, 6250.312465628777, 6285.811425657238, 6307.96846015767, 6325.96837015812, 6345.841353966188, 6111.052786818051, 6241.126242325987, 6252.0, 6254.655993920378, 6271.6550589718, 6267.4045965633195, 6283.3088360281245, 6284.968575157278, 6291.96854015727, 6298.748050077946, 5086.237064440215, 6077.726502307319, 6150.815475535741, 6152.446279834837, 6163.260408750979, 6161.661108639068, 6197.442230199304, 6208.906866397098, 6235.282942461675, 6255.593386429868, 5690.516306113861, 5844.415558444121, 5911.201987731602, 5960.04639257734, 5983.431574000463, 5896.027155519463, 5952.642841429528, 5990.550708696778, 6031.668258245663, 6049.032154855202, 5806.82579522615, 5806.883862322815, 5815.5929084963645, 5824.737886795019, 5829.183914252093, 5848.824535263949, 5920.052791553503, 5942.61373010753, 5951.315598706031, 5958.1658567801405, 5792.362840087671, 5822.970885145547, 5847.356790753099, 5856.238688970407, 5868.853278667897], "value": 5646.834686261802, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.2789929056061, 516.7261351483712, 516.8165301318032, 537.1700722383916, 554.750362336948, 29777.851110744443, 37988.67039653613, 38849.2517836698, 38987.61012389879, 39012.02469938253, 42208.78895605525, 42732.0, 42762.57237427625, 42790.57209427905, 42839.78580107095, 8415.0, 9420.764480887981, 9631.229501639888, 9645.083717046904, 9652.469114198717, 8500.574971251444, 11572.884271157307, 11779.0, 11800.46897889597, 11823.7635247295, 11245.775084498306, 11547.076233901309, 11556.075513958864, 11563.826542601868, 11570.0, 8545.145485451467, 11419.885801142007, 11508.827367589492, 11531.365774882379, 11540.192186546903, 11452.484638191301, 11578.536858525733, 11584.594539191172, 11595.536178552848, 11622.883771162265, 38940.02649933746, 39295.4105688412, 39376.0, 39456.60543394558, 39483.802580986914, 3197.552342672028, 3261.3314270574474, 3267.722243609296, 3286.277019055804, 3307.2228026413773, 22393.776062239333, 23768.04927802886, 27825.86087069572, 28714.707838147333, 28889.988850390346, 2707.3502359433596, 2732.699403065661, 2753.9449211015667, 2758.4069425073703, 2765.25338158696, 495094.0, 497419.0, 498295.0, 498450.0, 499034.50482747715, 59598.404015959284, 60888.69555652282, 60985.695071525246, 60996.69501652463, 61199.38800612026, 66047.33952660508, 68864.0, 70178.2982170182, 70180.2981970184, 70221.64889175522, 17583.912080439517, 17780.822191777916, 17815.643687126183, 17832.91083544574, 17834.732479012826, 12396.132270741142, 12631.747365052648, 13389.598312050653, 13408.061435699603, 13459.057865949486, 16302.83697163037, 19077.950712710935, 19095.331663391713, 19113.61772764537, 19190.712139317922, 7629.122650895107, 7784.182660820532, 7787.415943804237, 7888.3689304856625, 7896.526208427513, 942.0908822985851, 953.7520244736419, 963.8987906269741, 988.3723835364451, 1077.3051381858656, 23107.653385199235, 27973.0, 29370.265743356158, 29653.555196672067, 30316.24209394783, 1082.9025387715144, 1130.1580322659672, 1162.8023236049892, 1210.0984766349127, 1229.4098832560421, 15135.621609459631, 15378.461753838787, 15434.073955562702, 15456.55747123554, 15650.921745391428, 645607.0, 650932.0, 654514.0, 654891.0, 655679.0, 54478.455215447335, 61088.694556526934, 61164.0, 61228.38771612316, 61268.69365653233, 1300.4603089717662, 1349.939252733649, 1350.2371160294401, 1388.861133870237, 1486.9628259293388, 23384.532309353377, 29911.0, 29926.25184370408, 30913.0, 31458.0, 45760.7711961438, 56133.87732245414, 57227.0, 57418.42581574298, 57489.42510574841, 576850.0, 580825.0, 581620.0, 581825.0, 582067.0, 24106.0, 27095.0, 27489.72510274872, 28153.296167595974, 28468.85765571159, 44951.775241123585, 45082.54917450915, 46601.766991164826, 46886.062278753554, 46890.76554617342, 36532.0, 36941.63058369382, 37808.62191378051, 37820.24359512849, 38502.6149738499, 36933.63066369409, 37252.25495490129, 37263.0, 37526.43710344292, 38242.426363605126, 36711.816440917624, 37203.6279637211, 37551.43672844964, 37806.0, 37821.243575128894, 32815.95789221798, 33104.0, 33238.16904577405, 33324.50013249851, 33556.0, 24573.38566535851, 24639.137630183573, 25010.499790004462, 25174.370640734127, 25389.492210155324], "value": 20253.808829460766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.7996161938221, 399.147819405569, 399.43280541630884, 401.50414238415544, 404.9514058313003, 19640.901795491, 20091.29680461184, 20757.16971321147, 20757.688634670456, 20785.896070519644, 20423.59152816943, 20476.0, 20870.0, 21247.15011399546, 21366.145354185854, 5837.066069428893, 6001.069834175699, 6155.045967874987, 6162.815115546518, 6201.131841542187, 5994.0, 6068.0291153415465, 6099.695015249242, 6103.084537319415, 6115.205023346959, 5816.1275808628625, 6022.0, 6042.0, 6150.630962142263, 6150.815475535741, 6131.202943617323, 6143.109249158847, 6174.073888916653, 6191.938080619181, 6192.040233763774, 5684.289463817023, 5954.642721436728, 5988.490978266853, 5991.940080599182, 5995.760169593211, 13672.863271367258, 13858.16850988944, 13912.721745565032, 21683.891580542153, 25452.618210726854, 2107.8629889057315, 2184.595849767798, 2188.627933251335, 2214.878181700006, 2504.799616030713, 9970.601175952954, 15418.614534636612, 15504.612384690361, 15717.21413929299, 16242.837571624368, 1623.1640705036916, 1643.7534369844618, 1651.1826645810281, 1847.3349594146096, 2011.0849563448596, 122572.0, 125541.0, 127704.0, 128951.0, 133251.0, 17130.828691712923, 21684.34946951594, 21752.673709894363, 21913.452163695718, 22082.558348833256, 35716.0, 35748.0, 36452.81773591115, 36652.26695466076, 36687.899363019154, 7180.7845764627145, 8553.657853685907, 8892.466452012899, 8956.776080598038, 8993.910060899307, 8879.733607991771, 8904.910950890408, 8914.55427228636, 8980.55097245135, 9001.909980900238, 4719.551642593948, 4748.382710247645, 4759.405074365704, 4772.164871147535, 4862.07620552094, 1695.6947749405226, 1929.7877233504303, 1972.3294080012865, 1995.6806910894188, 1996.3112726109468, 839.1901814748796, 847.9533625650525, 972.9513524323755, 984.7931934293736, 1001.8948010458943, 17827.10864456798, 20114.39656810298, 20894.686579701316, 20939.267125650527, 21050.15799368008, 940.3323640215524, 951.4386511957978, 986.0927946289431, 993.2252842782641, 995.7958618483193, 10823.296485728402, 11189.0, 11474.738188504263, 11691.76616467666, 11755.706107347385, 611498.0, 618449.0, 625136.0, 627015.0, 632865.0, 133809.0, 135925.0, 136188.0, 139855.30072349572, 140495.0, 1017.2574020964589, 1026.5585798106886, 1047.8113939490966, 1050.9421981791074, 1052.3685788526823, 18398.596259692, 18543.258269669062, 18924.621507569496, 18957.620847582697, 19131.90434047821, 56201.437985621254, 56226.43773562376, 56316.71841640765, 59291.70354148201, 59958.0, 81840.0, 82808.17191828004, 83886.0, 84216.0, 84217.57891210505, 7672.156062833195, 7738.110117336579, 8175.877361839696, 8331.583420829056, 8424.115467875785, 10340.27618066728, 12553.623391298275, 12637.810432843331, 12977.740445191232, 12996.220226786423, 10866.022058014816, 11008.064314533034, 11171.72070698239, 11201.607943722256, 11276.436178191221, 10777.976092271221, 10822.350658960488, 10834.404107774148, 10959.232853700165, 10984.340939543654, 11101.222914395914, 11131.443427828739, 11424.829937073075, 11556.0, 11583.942080289882, 8150.633221504928, 8288.58557072156, 8299.460535065444, 8329.416940814084, 8363.581820908808, 8478.0, 10429.269951103654, 10802.513886874953, 11052.502637381498, 11152.665420037412], "value": 10174.275232634202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.1948469887466, 1501.7747337899314, 1523.0785374848217, 1546.4664690681711, 1948.1915005272813, 7717.845643087135, 8309.210624990625, 8479.830403391928, 8491.957540212306, 8492.235698787108, 8157.714479993187, 8378.99452065753, 8395.118512556186, 8398.49609023459, 8408.83182336353, 2124.808767210951, 2178.0307763045453, 2184.868907865525, 2365.207655435431, 2409.1929203716777, 2388.0686532252435, 2433.9513209735796, 2438.4879175373194, 2440.414300567869, 2440.9145679901208, 2305.6080466320764, 2330.3125577954543, 2335.1359996801207, 2342.215357855112, 2361.633946738258, 2117.3224568138203, 2284.6001949658744, 2396.3769419950763, 2398.2445529658103, 2402.4594466244953, 2357.9174728884577, 2395.3652282145244, 2395.7843794058617, 2400.0519794681004, 2401.3156250468533, 8382.622781974826, 8444.944381952257, 8460.450070745383, 8467.153284671545, 8490.66037358505, 4219.38818871262, 5222.3472065991755, 5225.608079394023, 5250.68495890244, 5255.710935898524, 3367.6295607483157, 3435.089701229176, 3502.9299414011575, 3529.7882127072457, 3543.273628906094, 1024.6311327921942, 1038.8234000219904, 1039.7140786283833, 1065.280935368631, 1075.9731006724817, 11513.827292590619, 11705.297682139098, 11719.296842189497, 11719.414029298501, 11729.296242225493, 3947.703922205789, 4581.289900065495, 4667.159911216014, 4718.905621887543, 4797.376341075638, 9956.00879824038, 9975.750606234902, 9983.450910200008, 9991.100800927952, 10013.29906906524, 5287.894242115136, 5291.0, 5331.226972089025, 5340.118880384693, 5340.172273297645, 4345.587169218919, 4448.0, 4689.226277664216, 4867.634927380461, 4875.341828853059, 3907.8632247871187, 3936.7244292899795, 3990.3016972029777, 4016.979915100406, 4021.8994525136522, 1291.3930452687223, 1309.3845892430543, 1310.4496111633136, 1325.5360623781792, 1326.303690562443, 875.872998415239, 897.8249241397996, 900.8963969143501, 916.1983264643437, 917.8439665256923, 5608.551315894719, 5634.239377684042, 5772.9243264007055, 5808.912866307009, 5939.732712027969, 970.262600423669, 983.3460748602242, 983.5623147699348, 997.6159178716247, 1027.5581499955092, 3771.358868992223, 3806.238752249561, 3859.9614003860165, 3943.7633741975574, 3953.525576930787, 9749.317547771589, 10594.735131621617, 10597.9470102649, 10707.785844283226, 10724.73188170302, 10253.74365640865, 10451.581936722447, 10565.418901960466, 10610.681679549625, 10614.681559553226, 7884.763457096296, 8227.670893164206, 8236.876446853303, 8241.134680858544, 8282.4202305838, 5478.479544443431, 5495.038368285614, 5639.333180909961, 5730.111832665943, 5737.196792448977, 7591.8861217082895, 9100.635974560944, 9391.85912211331, 9566.712998610054, 9569.61721531131, 9437.716868493957, 9722.368046076983, 9919.702408927744, 9945.154661853823, 9959.950200248952, 6355.110284560258, 6444.742210311535, 6448.1295025171, 6729.427998620172, 6759.5606285591275, 7405.2224516425, 7462.253774622494, 7473.215312392121, 7829.29536341732, 7953.363730901629, 7015.438764898694, 7093.751718689821, 7100.609466479394, 7210.603416812126, 7225.096862892138, 7031.050808140835, 7059.223485416703, 7087.184973728087, 7140.7857764267155, 7155.785326440215, 6666.933330666825, 6733.956236783307, 6741.696623651849, 6760.0, 6789.321067893171, 4762.928556071731, 4853.199222128309, 4870.488598697086, 4949.430815456125, 5034.169362055366, 4925.507449255046, 4982.227754698028, 4986.401631804207, 5061.974690126526, 5205.427402985744], "value": 4491.938390518841, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.33387533605, 278.8549954023909, 296.1308559378225, 328.6795374509853, 336.81811821616327, 5433.320834895638, 6071.028635418334, 6386.552941294111, 6415.45468635166, 6442.065900444439, 5669.09294512878, 5759.395263497336, 5974.0, 6449.838754031151, 6453.709583068761, 4034.253663072341, 4692.085043416535, 4781.737004464753, 4816.542428469307, 4825.2279635258365, 4819.493953134923, 4896.143174944371, 4896.55930966213, 4897.534734200245, 4898.14282500561, 4838.395200599925, 4840.128776820171, 4840.612750979931, 4844.491328410519, 4848.078865015654, 4568.497465278817, 4653.930191047137, 4754.904901901978, 4767.523247675239, 4769.737664428455, 3349.330133973215, 3803.3835619861447, 4064.8983775405554, 4075.266452038632, 4096.283150448659, 5545.833624991256, 6240.750369985195, 6253.6247825130185, 6264.809686159615, 6274.905876411857, 5615.9157612635845, 5641.323041235078, 5643.435656434365, 5663.461971112738, 5675.5459563234845, 6893.20728116263, 6899.551529150639, 6906.965465172692, 6916.723331066752, 6935.237123916365, 4305.418768466248, 4458.866234012985, 4488.685791994561, 4509.819607215707, 4516.435445569304, 7191.065161528968, 7287.453440991947, 7317.707291708325, 7338.77983660491, 7341.853162936712, 4564.817407303737, 4587.610053145453, 4590.357349971006, 4603.056373443436, 4609.746463944515, 4637.9536204637525, 4641.837535686234, 4641.930371044437, 4643.0, 4653.53464653532, 5500.202470641814, 5521.861953451196, 5536.335639723259, 5542.722863856791, 5543.722813859291, 5652.802151924745, 6664.5334826561675, 6918.723251070001, 6937.479689023344, 6995.650217489207, 1597.225345707331, 1597.9201039947957, 1614.3784642912328, 1621.9270132844051, 1622.4483675550134, 959.5681943125621, 971.155095067296, 1012.7569383347943, 1014.3862962907482, 1040.8802987656366, 6489.0, 6602.240742314695, 6604.405603495708, 6613.140291762136, 6621.437177839937, 7274.96362518184, 7287.161976372785, 7294.051773269441, 7308.451866110063, 7309.561426314438, 6579.276279609239, 6579.901301480481, 6583.210014798255, 6595.934040659532, 6603.108580341688, 3784.2942296257756, 3842.9807850960565, 3845.788481633481, 3851.614838516182, 3857.7685338879755, 6130.846728831726, 6199.876002480015, 7381.81545461357, 7636.236376362319, 7751.573663448452, 6334.018227174795, 7360.889586656204, 7491.325780679765, 7508.3993280537425, 7536.6985320587655, 7645.4648174628355, 7668.463207575416, 7670.271324224302, 7672.309492145622, 7674.6930122794465, 6963.756268530779, 7271.236520165518, 7279.854402911807, 7311.159216689936, 7318.780436586911, 7072.434205263669, 7077.6461176941975, 7107.466939979418, 7135.571865687868, 7174.856502870018, 7688.577128257776, 7701.499402538817, 7730.652120654697, 7736.381089512938, 7739.110002349802, 4579.496255411968, 4627.745473998963, 4639.118567472305, 4645.930311045269, 4649.628029757679, 5860.238169037998, 5913.970430147821, 7035.753748618774, 7262.128544574685, 7275.163356214103, 6837.829054273484, 6979.1973922999505, 6993.930060699327, 7019.052427922369, 7024.438044956289, 6912.101426814482, 6967.930320696728, 6971.965140174267, 6993.0, 7009.088818453773, 6833.4191593715095, 6835.111435513351, 6840.623765692934, 6851.691673874588, 6856.142982127234, 6731.394174524316, 6923.342282483156, 6945.236024037251, 6969.512134150561, 6975.6163411012885, 5347.358317001985, 6505.8698826024165, 6722.193336799615, 6741.764038258638, 6875.7937261882225], "value": 5036.688759469735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.9166100067991, 754.5585832288111, 763.7975936376861, 772.1892013385947, 783.2441693765516, 14861.40554377825, 14967.101973881574, 15250.0, 15292.0, 15299.92350038251, 13666.38501267443, 13878.861211387908, 13890.930545347284, 13899.374528146232, 13917.652058698537, 751.9285667861544, 758.0675768804374, 905.180811365715, 933.9206167475772, 935.4480856294784, 909.8817153770001, 935.3218916285673, 939.6523286384047, 940.7036783413203, 942.2226663003015, 954.9856752148723, 966.2704657983213, 969.3747532841348, 974.0794948773412, 975.624384611926, 782.2842099478999, 952.2239374909427, 962.8122516109362, 966.0387914025555, 978.6281213138961, 710.3819676881095, 720.0063911801733, 724.8187953011748, 724.8695234857723, 725.1117381207978, 13638.567950365168, 13861.861381386158, 15111.54665360041, 15125.470608528649, 15150.0, 12613.558525451657, 12845.871541284561, 12875.935620321838, 12932.48270069196, 12937.741245175044, 9356.204722598588, 9429.952850235704, 9472.621095156186, 10107.0, 10434.321769084985, 3460.325236578868, 3539.716822654207, 3600.873969411058, 3615.132368231632, 3625.474306225609, 85858.28283434358, 85985.0, 88527.11472885318, 88922.77693057661, 88972.0, 9408.200302974186, 9462.195713364303, 9616.807663846685, 9673.419594824332, 9800.55897484615, 21101.577968440546, 21382.78617213808, 21399.0, 21438.24966126178, 22919.885400573225, 7525.924740752632, 7529.171791102975, 7707.922920770833, 7807.80480487981, 7917.564533950688, 4139.130782535641, 4199.4330765346895, 4211.642010429148, 4284.271673815459, 4300.569943005675, 3643.3441980443245, 3708.147126160998, 3708.9258214835554, 3712.7401081924004, 3717.237966216919, 631.0975305313445, 638.9041643753474, 641.6791604197902, 653.4543656047254, 661.024988142486, 546.2625455634941, 593.5631157100638, 594.3610618584952, 613.2457077794338, 620.7610070123035, 7739.883901741478, 8170.305524030405, 8281.21328473794, 8283.875741863876, 8399.0, 495.752123938031, 531.7288183026607, 574.4686165297084, 582.6911736779514, 614.2904944788776, 3616.855325786939, 3834.616538346143, 3925.6961002424437, 3931.253061918226, 4036.616521430459, 90664.54667726619, 94087.58868616977, 94203.52898235465, 94295.0, 94302.52848735849, 71384.64307678428, 74640.88038679425, 74858.6257068722, 75510.62244688741, 76369.0, 70350.64824675844, 70386.0, 70441.0, 71203.64398177977, 71702.2829771706, 7111.9288807113335, 7633.351165151024, 7764.805879853049, 7899.09160446533, 7948.523088614702, 44085.67742967716, 48156.759216203696, 48282.0, 48332.0, 48575.27137093017, 62921.05618415819, 65982.0, 66936.0, 67012.0, 68629.31370686229, 10086.798264034825, 10274.743131421774, 10284.640037598649, 10308.226882983656, 10382.961703829858, 11026.117910566974, 11112.444377781241, 11186.776264474827, 11492.0, 11570.88429115698, 10010.499475026365, 10295.897041029493, 10428.113610343205, 10604.946975265075, 10634.41510716918, 10402.58389664405, 10456.954304569786, 10576.629817956335, 10721.571137154739, 10972.561097556008, 10049.346792458467, 10120.949395252977, 10152.746181345525, 10227.488625568543, 10779.407132607781, 8838.734837954871, 9063.546822658709, 9079.636814527345, 9090.590923408594, 9297.209737172145, 8657.95671021641, 8713.607887645197, 8800.779980500538, 8917.554122293988, 9446.95276523613], "value": 6815.403872841325, "unit": "score"}], "name": "dromaeo_css", "value": 7510.2502830834455, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 119.0, 110.0, 109.0, 110.0, 113.0, 111.0, 112.0, 133.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 158.0, 158.0, 119.0, 142.0, 120.0, 152.0, 162.0, 119.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 198.0, 262.0, 203.0, 185.0, 184.0, 182.0, 181.0, 184.0, 184.0], "value": 194.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 82.0, 183.0, 84.0, 82.0, 57.0, 65.0, 132.0, 55.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 138.0, 77.0, 76.0, 86.0, 123.0, 79.0, 80.0, 125.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 96.0, 135.0, 85.0, 90.0, 122.0, 88.0, 84.0, 86.0, 85.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 92.0, 111.0, 119.0, 92.0, 92.0, 92.0, 94.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 83.0, 83.0, 83.0, 90.0, 84.0, 83.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 64.0, 63.0, 63.0, 65.0, 65.0, 80.0, 60.0, 60.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 77.0, 105.0, 99.0, 59.0, 89.0, 91.0, 99.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 81.0, 77.0, 79.0, 80.0, 80.0, 82.0, 86.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 128.0, 139.0, 145.0, 144.0, 120.0, 126.0, 147.0, 134.0], "value": 134.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 167.0, 164.0, 166.0, 164.0, 164.0, 174.0, 165.0, 169.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 67.0, 68.0, 66.0, 67.0, 71.0, 66.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1507.9999999999998, "alertThreshold": 2.0}]} 03:30:54 INFO - Return code: 0 03:30:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:30:54 ERROR - # TBPL SUCCESS # 03:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:30:54 INFO - 2016-05-28 03:30:54.659 screenresolution[2226:24531] starting screenresolution argv=screenresolution get 03:30:54 INFO - 2016-05-28 03:30:54.662 screenresolution[2226:24531] Display 0: 1600x1200x32@60 03:30:54 INFO - 2016-05-28 03:30:54.668 screenresolution[2227:24533] starting screenresolution argv=screenresolution list 03:30:54 INFO - Available Modes on Display 0 03:30:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:30:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:30:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:30:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:30:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:30:54 INFO - Intel Iris: 03:30:54 INFO - Chipset Model: Intel Iris 03:30:54 INFO - Type: GPU 03:30:54 INFO - Bus: Built-In 03:30:54 INFO - VRAM (Dynamic, Max): 1536 MB 03:30:54 INFO - Vendor: Intel (0x8086) 03:30:54 INFO - Device ID: 0x0a2e 03:30:54 INFO - Revision ID: 0x0009 03:30:54 INFO - Displays: 03:30:54 INFO - Display: 03:30:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:30:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:30:54 INFO - Display Serial Number: 1600x1200 60 03:30:54 INFO - Main Display: Yes 03:30:54 INFO - Mirror: Off 03:30:54 INFO - Online: Yes 03:30:54 INFO - Rotation: Supported 03:30:54 INFO - Return code: 0 03:30:54 INFO - Running post-action listener: _resource_record_post_action 03:30:54 INFO - Running post-run listener: _resource_record_post_run 03:30:55 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 634880; Write bytes: 657765376; Read time: 7; Write time: 1851 03:30:55 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 348709376; Write bytes: 346347008; Read time: 27508; Write time: 722 03:30:55 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 634880; Write bytes: 300625408; Read time: 7; Write time: 1091 03:30:55 INFO - Running post-run listener: _upload_blobber_files 03:30:55 INFO - Blob upload gear active. 03:30:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:30:55 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 03:30:55 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:30:55 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:30:56 INFO - (blobuploader) - INFO - Open directory for files ... 03:30:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:30:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:30:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:30:57 INFO - (blobuploader) - INFO - Done attempting. 03:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:30:58 INFO - (blobuploader) - INFO - Done attempting. 03:30:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:30:58 INFO - Return code: 0 03:30:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:30:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:30:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7e2a9596e5ebe556fdb3d4651ea4a5e047a71b5b25419aa1693d8346015fccb095d1cfffb7be29d90375cbcb511428d9deeb24eb73b8251792a796ced7514ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3af9ca2fddd82bdbfe5db567f538fcfaff142b95fea3f596e800aa45ca1b3aba8c8ef1e5371c1f29d1173e0bd9b3ee611d310da6706ae09414a48423b06f0464"} 03:30:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:30:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:30:58 INFO - Contents: 03:30:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7e2a9596e5ebe556fdb3d4651ea4a5e047a71b5b25419aa1693d8346015fccb095d1cfffb7be29d90375cbcb511428d9deeb24eb73b8251792a796ced7514ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3af9ca2fddd82bdbfe5db567f538fcfaff142b95fea3f596e800aa45ca1b3aba8c8ef1e5371c1f29d1173e0bd9b3ee611d310da6706ae09414a48423b06f0464"} 03:30:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:30:58 INFO - Copying logs to upload dir... 03:30:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:30:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.417341 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-28 03:30:58.206298) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:30:58.209733) ========= 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.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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/Mozilla-Aurora/sha512/f7e2a9596e5ebe556fdb3d4651ea4a5e047a71b5b25419aa1693d8346015fccb095d1cfffb7be29d90375cbcb511428d9deeb24eb73b8251792a796ced7514ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3af9ca2fddd82bdbfe5db567f538fcfaff142b95fea3f596e800aa45ca1b3aba8c8ef1e5371c1f29d1173e0bd9b3ee611d310da6706ae09414a48423b06f0464"} build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011487 build_url: 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7e2a9596e5ebe556fdb3d4651ea4a5e047a71b5b25419aa1693d8346015fccb095d1cfffb7be29d90375cbcb511428d9deeb24eb73b8251792a796ced7514ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3af9ca2fddd82bdbfe5db567f538fcfaff142b95fea3f596e800aa45ca1b3aba8c8ef1e5371c1f29d1173e0bd9b3ee611d310da6706ae09414a48423b06f0464"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:30:58.239873) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:30:58.240167) ========= 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.oscRCsa6So/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.qPJqbdMg8U/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.005296 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:30:58.288770) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:30:58.289066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:30:58.289501) ========= ========= Total master_lag: 0.17 =========