builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1466526198.86 results: success (0) buildid: 20160621081102 builduid: 964f703828154d979a8003f131c33f10 revision: fb8483f7bc37750e0bf19549141c922d37262c3d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:18.864281) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:18.864687) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:18.864961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:18.889558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:18.889848) ========= 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.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-06-21 09:23:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2016-06-21 09:23:19 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.533789 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:19.465771) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:19.466118) ========= 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.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.025410 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:19.526875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-21 09:23:19.527207) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fb8483f7bc37750e0bf19549141c922d37262c3d --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-beta --rev fb8483f7bc37750e0bf19549141c922d37262c3d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-06-21 09:23:19,619 truncating revision to first 12 chars 2016-06-21 09:23:19,619 Setting DEBUG logging. 2016-06-21 09:23:19,619 attempt 1/10 2016-06-21 09:23:19,619 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fb8483f7bc37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 09:23:20,702 unpacking tar archive at: mozilla-beta-fb8483f7bc37/testing/mozharness/ program finished with exit code 0 elapsedTime=1.347543 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-21 09:23:20.904212) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:20.904495) ========= script_repo_revision: fb8483f7bc37750e0bf19549141c922d37262c3d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:20.904917) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:20.905181) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 09:23:20.924967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-21 09:23:20.925230) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:23:21 INFO - MultiFileLogger online at 20160621 09:23:21 in /builds/slave/test 09:23:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:23:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:23:21 INFO - {'append_to_log': False, 09:23:21 INFO - 'base_work_dir': '/builds/slave/test', 09:23:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:23:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:23:21 INFO - 'branch': 'Mozilla-Beta', 09:23:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:21 INFO - 'config_files': ('talos/mac_config.py',), 09:23:21 INFO - 'default_actions': ('clobber', 09:23:21 INFO - 'read-buildbot-config', 09:23:21 INFO - 'download-and-extract', 09:23:21 INFO - 'populate-webroot', 09:23:21 INFO - 'create-virtualenv', 09:23:21 INFO - 'install', 09:23:21 INFO - 'run-tests'), 09:23:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:21 INFO - 'download_minidump_stackwalk': True, 09:23:21 INFO - 'download_symbols': 'ondemand', 09:23:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:21 INFO - 'installer_path': 'installer.exe', 09:23:21 INFO - 'log_level': 'info', 09:23:21 INFO - 'log_name': 'talos', 09:23:21 INFO - 'log_to_console': True, 09:23:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:23:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:23:21 INFO - 'opt_config_files': (), 09:23:21 INFO - 'pip_index': False, 09:23:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:21 INFO - 'cmd': ('bash', 09:23:21 INFO - '-c', 09:23:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:21 INFO - 'enabled': True, 09:23:21 INFO - 'halt_on_failure': False, 09:23:21 INFO - 'name': 'check_screen_resolution'},), 09:23:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:21 INFO - 'cmd': ('bash', 09:23:21 INFO - '-c', 09:23:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:21 INFO - 'enabled': True, 09:23:21 INFO - 'halt_on_failure': False, 09:23:21 INFO - 'name': 'check_screen_resolution'},), 09:23:21 INFO - 'run_cmd_checks_enabled': True, 09:23:21 INFO - 'sps_profile': False, 09:23:21 INFO - 'sps_profile_interval': 0, 09:23:21 INFO - 'suite': 'dromaeojs-e10s', 09:23:21 INFO - 'system_bits': '32', 09:23:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:21 INFO - 'title': 't-yosemite-r7-0342', 09:23:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:21 INFO - 'use_talos_json': True, 09:23:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:23:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:21 INFO - 'work_dir': 'build'} 09:23:21 INFO - ##### 09:23:21 INFO - ##### Running clobber step. 09:23:21 INFO - ##### 09:23:21 INFO - Running pre-action listener: _resource_record_pre_action 09:23:21 INFO - Running main action method: clobber 09:23:21 INFO - rmtree: /builds/slave/test/build 09:23:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:23:23 INFO - Running post-action listener: _resource_record_post_action 09:23:23 INFO - ##### 09:23:23 INFO - ##### Running read-buildbot-config step. 09:23:23 INFO - ##### 09:23:23 INFO - Running pre-action listener: _resource_record_pre_action 09:23:23 INFO - Running main action method: read_buildbot_config 09:23:23 INFO - Using buildbot properties: 09:23:23 INFO - { 09:23:23 INFO - "project": "", 09:23:23 INFO - "product": "firefox", 09:23:23 INFO - "script_repo_revision": "production", 09:23:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:23:23 INFO - "repository": "", 09:23:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:23:23 INFO - "buildid": "20160621081102", 09:23:23 INFO - "pgo_build": "False", 09:23:23 INFO - "basedir": "/builds/slave/test", 09:23:23 INFO - "buildnumber": 3, 09:23:23 INFO - "slavename": "t-yosemite-r7-0342", 09:23:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:23:23 INFO - "platform": "yosemite_r7", 09:23:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:23:23 INFO - "branch": "mozilla-beta", 09:23:23 INFO - "repo_path": "releases/mozilla-beta", 09:23:23 INFO - "revision": "fb8483f7bc37750e0bf19549141c922d37262c3d", 09:23:23 INFO - "stage_platform": "macosx64", 09:23:23 INFO - "builduid": "964f703828154d979a8003f131c33f10", 09:23:23 INFO - "slavebuilddir": "test" 09:23:23 INFO - } 09:23:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:23:23 INFO - Running post-action listener: _resource_record_post_action 09:23:23 INFO - ##### 09:23:23 INFO - ##### Running download-and-extract step. 09:23:23 INFO - ##### 09:23:23 INFO - Running pre-action listener: _resource_record_pre_action 09:23:23 INFO - Running main action method: download_and_extract 09:23:23 INFO - mkdir: /builds/slave/test/build/tests 09:23: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')]} 09:23:23 INFO - https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:23:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:23:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:23:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:23:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 09:23:25 INFO - Downloaded 1165 bytes. 09:23:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:23:25 INFO - Using the following test package requirements: 09:23:25 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:23:25 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:23:25 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:23:25 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:23:25 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:23:25 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:23:25 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:23:25 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:23:25 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:23:25 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:23:25 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:23:25 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:25 INFO - https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:23:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:23:26 INFO - Downloaded 18301157 bytes. 09:23:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:23:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:23:29 INFO - Return code: 0 09:23:29 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:29 INFO - https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:23:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:23:34 INFO - Downloaded 18301157 bytes. 09:23:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:23:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:23:38 INFO - Return code: 0 09:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:38 INFO - https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:23:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:23:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:23:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 09:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 09:23:39 INFO - Downloaded 10840145 bytes. 09:23:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:23:39 INFO - Return code: 0 09:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:39 INFO - https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:23:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:23:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:23:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:23:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 09:23:49 INFO - Downloaded 86247541 bytes. 09:23:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:23:49 INFO - mkdir: /builds/slave/test/properties 09:23:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:23:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:23:49 INFO - Contents: 09:23:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:23:50 INFO - Running post-action listener: _resource_record_post_action 09:23:50 INFO - Running post-action listener: set_extra_try_arguments 09:23:50 INFO - ##### 09:23:50 INFO - ##### Running populate-webroot step. 09:23:50 INFO - ##### 09:23:50 INFO - Running pre-action listener: _resource_record_pre_action 09:23:50 INFO - Running main action method: populate_webroot 09:23:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:50 INFO - '--tppagecycles', 09:23:50 INFO - '7'], 09:23:50 INFO - 'tests': ['tsvgm']}}, 09:23:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:23:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:23:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:23:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['damp', 'tps']}, 09:23:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'tests': ['damp', 'tps']}, 09:23:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['dromaeo_dom']}, 09:23:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['a11yr', 09:23:50 INFO - 'ts_paint', 09:23:50 INFO - 'tpaint', 09:23:50 INFO - 'sessionrestore', 09:23:50 INFO - 'sessionrestore_no_auto_restore', 09:23:50 INFO - 'tabpaint']}, 09:23:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['tsvgx', 09:23:50 INFO - 'tsvgr_opacity', 09:23:50 INFO - 'tart', 09:23:50 INFO - 'tscrollx', 09:23:50 INFO - 'cart']}, 09:23:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:23:50 INFO - 'tsvgr_opacity', 09:23:50 INFO - 'tart', 09:23:50 INFO - 'tscrollx', 09:23:50 INFO - 'cart']}, 09:23:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'talos_options': ['--disable-e10s'], 09:23:50 INFO - 'tests': ['tp5o']}, 09:23:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'tests': ['tp5o']}, 09:23:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'talos_options': ['--disable-e10s', 09:23:50 INFO - '--xperf_path', 09:23:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:50 INFO - 'tests': ['tp5n']}, 09:23:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:50 INFO - 'talos_options': ['--xperf_path', 09:23:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:50 INFO - 'tests': ['tp5n']}}, 09:23:50 INFO - 'talos.zip': {'path': '', 09:23:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:50 INFO - Running post-action listener: _resource_record_post_action 09:23:50 INFO - ##### 09:23:50 INFO - ##### Running create-virtualenv step. 09:23:50 INFO - ##### 09:23:50 INFO - Running pre-action listener: _resource_record_pre_action 09:23:50 INFO - Running main action method: create_virtualenv 09:23:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:23:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:23:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:23:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:50 INFO - Using real prefix '/tools/python27' 09:23:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:23:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:23:54 INFO - Installing pip.................done. 09:23:54 INFO - Return code: 0 09:23:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:54 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:54 INFO - 'HOME': '/Users/cltbld', 09:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:54 INFO - 'LOGNAME': 'cltbld', 09:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:54 INFO - 'MOZ_NO_REMOTE': '1', 09:23:54 INFO - 'NO_EM_RESTART': '1', 09:23:54 INFO - 'PAGER': '/bin/cat', 09:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:54 INFO - 'PWD': '/builds/slave/test', 09:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:54 INFO - 'SHELL': '/bin/bash', 09:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:23:54 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:54 INFO - 'USER': 'cltbld', 09:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:54 INFO - 'XPC_FLAGS': '0x0', 09:23:54 INFO - 'XPC_SERVICE_NAME': '0', 09:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:23:54 INFO - Cleaning up... 09:23:54 INFO - Return code: 0 09:23:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:54 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:54 INFO - 'HOME': '/Users/cltbld', 09:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:54 INFO - 'LOGNAME': 'cltbld', 09:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:54 INFO - 'MOZ_NO_REMOTE': '1', 09:23:54 INFO - 'NO_EM_RESTART': '1', 09:23:54 INFO - 'PAGER': '/bin/cat', 09:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:54 INFO - 'PWD': '/builds/slave/test', 09:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:54 INFO - 'SHELL': '/bin/bash', 09:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:23:54 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:54 INFO - 'USER': 'cltbld', 09:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:54 INFO - 'XPC_FLAGS': '0x0', 09:23:54 INFO - 'XPC_SERVICE_NAME': '0', 09:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:54 INFO - Downloading/unpacking psutil>=0.7.1 09:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:23:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:58 INFO - Installing collected packages: psutil 09:23:58 INFO - Running setup.py install for psutil 09:23:58 INFO - building 'psutil._psutil_osx' extension 09:23:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:23:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:23:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:23:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:23:58 INFO - building 'psutil._psutil_posix' extension 09:23:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:23:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:23:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:23:59 INFO - ^ 09:23:59 INFO - 1 warning generated. 09:23:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:23:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:59 INFO - Successfully installed psutil 09:23:59 INFO - Cleaning up... 09:23:59 INFO - Return code: 0 09:23:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:23:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:59 INFO - 'HOME': '/Users/cltbld', 09:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:59 INFO - 'LOGNAME': 'cltbld', 09:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:59 INFO - 'MOZ_NO_REMOTE': '1', 09:23:59 INFO - 'NO_EM_RESTART': '1', 09:23:59 INFO - 'PAGER': '/bin/cat', 09:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:59 INFO - 'PWD': '/builds/slave/test', 09:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:59 INFO - 'SHELL': '/bin/bash', 09:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:23:59 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:59 INFO - 'USER': 'cltbld', 09:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:59 INFO - 'XPC_FLAGS': '0x0', 09:23:59 INFO - 'XPC_SERVICE_NAME': '0', 09:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:24:02 INFO - Installing collected packages: mozsystemmonitor 09:24:02 INFO - Running setup.py install for mozsystemmonitor 09:24:02 INFO - Successfully installed mozsystemmonitor 09:24:02 INFO - Cleaning up... 09:24:02 INFO - Return code: 0 09:24:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:02 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:02 INFO - 'HOME': '/Users/cltbld', 09:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:02 INFO - 'LOGNAME': 'cltbld', 09:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:02 INFO - 'MOZ_NO_REMOTE': '1', 09:24:02 INFO - 'NO_EM_RESTART': '1', 09:24:02 INFO - 'PAGER': '/bin/cat', 09:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:02 INFO - 'PWD': '/builds/slave/test', 09:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:02 INFO - 'SHELL': '/bin/bash', 09:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:02 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:02 INFO - 'USER': 'cltbld', 09:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:02 INFO - 'XPC_FLAGS': '0x0', 09:24:02 INFO - 'XPC_SERVICE_NAME': '0', 09:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:06 INFO - Downloading docopt-0.6.1.tar.gz 09:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:06 INFO - Running setup.py install for blobuploader 09:24:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:06 INFO - Running setup.py install for requests 09:24:06 INFO - Running setup.py install for docopt 09:24:07 INFO - Successfully installed blobuploader requests docopt 09:24:07 INFO - Cleaning up... 09:24:07 INFO - Return code: 0 09:24:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:07 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:07 INFO - 'HOME': '/Users/cltbld', 09:24:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:07 INFO - 'LOGNAME': 'cltbld', 09:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:07 INFO - 'MOZ_NO_REMOTE': '1', 09:24:07 INFO - 'NO_EM_RESTART': '1', 09:24:07 INFO - 'PAGER': '/bin/cat', 09:24:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:07 INFO - 'PWD': '/builds/slave/test', 09:24:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:07 INFO - 'SHELL': '/bin/bash', 09:24:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:07 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:07 INFO - 'USER': 'cltbld', 09:24:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:07 INFO - 'XPC_FLAGS': '0x0', 09:24:07 INFO - 'XPC_SERVICE_NAME': '0', 09:24:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-XRt1EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ANZ8BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-r5Tmth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2_VDrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-G_u4F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-OZVkcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:07 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-OtnGAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-aUBb7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-axHjGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-vCy5ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-msROYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-KKWSH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AivBGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-S9wAko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uC2uLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:08 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-drKnFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:09 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-DZNKKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:09 INFO - Running setup.py install for manifestparser 09:24:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:09 INFO - Running setup.py install for mozcrash 09:24:09 INFO - Running setup.py install for mozdebug 09:24:09 INFO - Running setup.py install for mozdevice 09:24:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:09 INFO - Running setup.py install for mozfile 09:24:09 INFO - Running setup.py install for mozhttpd 09:24:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Running setup.py install for mozinfo 09:24:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Running setup.py install for mozInstall 09:24:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Running setup.py install for mozleak 09:24:10 INFO - Running setup.py install for mozlog 09:24:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Running setup.py install for moznetwork 09:24:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:10 INFO - Running setup.py install for mozprocess 09:24:10 INFO - Running setup.py install for mozprofile 09:24:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:11 INFO - Running setup.py install for mozrunner 09:24:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:11 INFO - Running setup.py install for mozscreenshot 09:24:11 INFO - Running setup.py install for moztest 09:24:11 INFO - Running setup.py install for mozversion 09:24:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:11 INFO - Cleaning up... 09:24:11 INFO - Return code: 0 09:24:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:11 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:11 INFO - 'HOME': '/Users/cltbld', 09:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:11 INFO - 'LOGNAME': 'cltbld', 09:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:11 INFO - 'MOZ_NO_REMOTE': '1', 09:24:11 INFO - 'NO_EM_RESTART': '1', 09:24:11 INFO - 'PAGER': '/bin/cat', 09:24:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:11 INFO - 'PWD': '/builds/slave/test', 09:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:11 INFO - 'SHELL': '/bin/bash', 09:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:11 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:11 INFO - 'USER': 'cltbld', 09:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:11 INFO - 'XPC_FLAGS': '0x0', 09:24:11 INFO - 'XPC_SERVICE_NAME': '0', 09:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:11 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-lyKHVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Safhy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:12 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)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-jt5M0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-GuE0U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Yx8AcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9vXrS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qGn7yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-RRvqOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AUYrEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:24:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:12 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-eEu1W5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SIwZhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-YIPVuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-x3SFI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ktVdFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-THbq_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uvQFnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:13 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5zpG4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:24:13 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)) 09:24:13 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)) 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:16 INFO - Downloading blessings-1.6.tar.gz 09:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:16 INFO - Installing collected packages: blessings 09:24:16 INFO - Running setup.py install for blessings 09:24:16 INFO - Successfully installed blessings 09:24:16 INFO - Cleaning up... 09:24:16 INFO - Return code: 0 09:24:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:16 INFO - Reading from file tmpfile_stdout 09:24:16 INFO - Current package versions: 09:24:16 INFO - blessings == 1.6 09:24:16 INFO - blobuploader == 1.2.4 09:24:16 INFO - docopt == 0.6.1 09:24:16 INFO - manifestparser == 1.1 09:24:16 INFO - mozInstall == 1.12 09:24:16 INFO - mozcrash == 0.17 09:24:16 INFO - mozdebug == 0.1 09:24:16 INFO - mozdevice == 0.48 09:24:16 INFO - mozfile == 1.2 09:24:16 INFO - mozhttpd == 0.7 09:24:16 INFO - mozinfo == 0.9 09:24:16 INFO - mozleak == 0.1 09:24:16 INFO - mozlog == 3.1 09:24:16 INFO - moznetwork == 0.27 09:24:16 INFO - mozprocess == 0.22 09:24:16 INFO - mozprofile == 0.28 09:24:16 INFO - mozrunner == 6.11 09:24:16 INFO - mozscreenshot == 0.1 09:24:16 INFO - mozsystemmonitor == 0.0 09:24:16 INFO - moztest == 0.7 09:24:16 INFO - mozversion == 1.4 09:24:16 INFO - psutil == 3.1.1 09:24:16 INFO - requests == 1.2.3 09:24:16 INFO - wsgiref == 0.1.2 09:24:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:16 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 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:16 INFO - 'HOME': '/Users/cltbld', 09:24:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:16 INFO - 'LOGNAME': 'cltbld', 09:24:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:16 INFO - 'MOZ_NO_REMOTE': '1', 09:24:16 INFO - 'NO_EM_RESTART': '1', 09:24:16 INFO - 'PAGER': '/bin/cat', 09:24:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:16 INFO - 'PWD': '/builds/slave/test', 09:24:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:16 INFO - 'SHELL': '/bin/bash', 09:24:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:16 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:16 INFO - 'USER': 'cltbld', 09:24:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:16 INFO - 'XPC_FLAGS': '0x0', 09:24:16 INFO - 'XPC_SERVICE_NAME': '0', 09:24:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:17 INFO - Cleaning up... 09:24:17 INFO - Return code: 0 09:24:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:17 INFO - 'HOME': '/Users/cltbld', 09:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:17 INFO - 'LOGNAME': 'cltbld', 09:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:17 INFO - 'MOZ_NO_REMOTE': '1', 09:24:17 INFO - 'NO_EM_RESTART': '1', 09:24:17 INFO - 'PAGER': '/bin/cat', 09:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:17 INFO - 'PWD': '/builds/slave/test', 09:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:17 INFO - 'SHELL': '/bin/bash', 09:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:17 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:17 INFO - 'USER': 'cltbld', 09:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:17 INFO - 'XPC_FLAGS': '0x0', 09:24:17 INFO - 'XPC_SERVICE_NAME': '0', 09:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:17 INFO - Downloading/unpacking jsonschema 09:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:19 INFO - Downloading/unpacking functools32 (from jsonschema) 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:20 INFO - warning: no files found matching '*.txt' 09:24:20 INFO - no previously-included directories found matching 'build' 09:24:20 INFO - no previously-included directories found matching 'dist' 09:24:20 INFO - no previously-included directories found matching '.git*' 09:24:20 INFO - Installing collected packages: jsonschema, functools32 09:24:20 INFO - Running setup.py install for functools32 09:24:20 INFO - warning: no files found matching '*.txt' 09:24:20 INFO - no previously-included directories found matching 'build' 09:24:20 INFO - no previously-included directories found matching 'dist' 09:24:20 INFO - no previously-included directories found matching '.git*' 09:24:20 INFO - Successfully installed jsonschema functools32 09:24:20 INFO - Cleaning up... 09:24:20 INFO - Return code: 0 09:24:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ab7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa21bc91210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, '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.bPVeLawLed/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.laLCoKwyqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24: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', 'flake8'] in /builds/slave/test/build 09:24: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 flake8 09:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:20 INFO - 'HOME': '/Users/cltbld', 09:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:20 INFO - 'LOGNAME': 'cltbld', 09:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:20 INFO - 'MOZ_NO_REMOTE': '1', 09:24:20 INFO - 'NO_EM_RESTART': '1', 09:24:20 INFO - 'PAGER': '/bin/cat', 09:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:20 INFO - 'PWD': '/builds/slave/test', 09:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:20 INFO - 'SHELL': '/bin/bash', 09:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:20 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:20 INFO - 'USER': 'cltbld', 09:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:20 INFO - 'XPC_FLAGS': '0x0', 09:24:20 INFO - 'XPC_SERVICE_NAME': '0', 09:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:20 INFO - Downloading/unpacking flake8 09:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:24:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:24: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 09:24: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 09:24: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 09:24: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 09:24: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 09:24: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 09:24:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:24:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:24: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 09:24: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 09:24: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 09:24: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 09:24: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 09:24: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 09:24:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:24:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:24:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:24:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:24:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:24:24 INFO - no previously-included directories found matching 'docs/_build' 09:24:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:24:24 INFO - Running setup.py install for pep8 09:24:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:24:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:24:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:24:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:24:24 INFO - no previously-included directories found matching 'docs/_build' 09:24:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:24:24 INFO - Cleaning up... 09:24:24 INFO - Return code: 0 09:24:24 INFO - Running post-action listener: _resource_record_post_action 09:24:24 INFO - Running post-action listener: _start_resource_monitoring 09:24:24 INFO - Starting resource monitoring. 09:24:24 INFO - ##### 09:24:24 INFO - ##### Running install step. 09:24:24 INFO - ##### 09:24:24 INFO - Running pre-action listener: _resource_record_pre_action 09:24:24 INFO - Running main action method: install 09:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:25 INFO - Reading from file tmpfile_stdout 09:24:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:25 INFO - Reading from file tmpfile_stdout 09:24:25 INFO - Output received: 09:24:25 INFO - Usage: mozinstall [options] installer 09:24:25 INFO - Options: 09:24:25 INFO - -h, --help show this help message and exit 09:24:25 INFO - -d DEST, --destination=DEST 09:24:25 INFO - Directory to install application into. [default: 09:24:25 INFO - "/builds/slave/test"] 09:24:25 INFO - --app=APP Application being installed. [default: firefox] 09:24:25 INFO - mkdir: /builds/slave/test/build/application 09:24:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:24:46 INFO - Reading from file tmpfile_stdout 09:24:46 INFO - Output received: 09:24:46 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:24:46 INFO - Running post-action listener: _resource_record_post_action 09:24:46 INFO - ##### 09:24:46 INFO - ##### Running run-tests step. 09:24:46 INFO - ##### 09:24:46 INFO - Running pre-action listener: _resource_record_pre_action 09:24:46 INFO - Running main action method: run_tests 09:24:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:46 INFO - 2016-06-21 09:24:46.900 screenresolution[1644:7730] starting screenresolution argv=screenresolution get 09:24:46 INFO - 2016-06-21 09:24:46.902 screenresolution[1644:7730] Display 0: 1600x1200x32@60 09:24:46 INFO - 2016-06-21 09:24:46.908 screenresolution[1645:7732] starting screenresolution argv=screenresolution list 09:24:46 INFO - Available Modes on Display 0 09:24:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:47 INFO - Intel Iris: 09:24:47 INFO - Chipset Model: Intel Iris 09:24:47 INFO - Type: GPU 09:24:47 INFO - Bus: Built-In 09:24:47 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:47 INFO - Vendor: Intel (0x8086) 09:24:47 INFO - Device ID: 0x0a2e 09:24:47 INFO - Revision ID: 0x0009 09:24:47 INFO - Displays: 09:24:47 INFO - Display: 09:24:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:47 INFO - Display Serial Number: 1600x1200 60 09:24:47 INFO - Main Display: Yes 09:24:47 INFO - Mirror: Off 09:24:47 INFO - Online: Yes 09:24:47 INFO - Rotation: Supported 09:24:47 INFO - Return code: 0 09:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:47 INFO - Python 2.7.3 09:24:47 INFO - Return code: 0 09:24:47 INFO - grabbing minidump binary from tooltool 09:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:47 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 0x103956e00>, '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 0x1037bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103951b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:24:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:24:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:47 INFO - Return code: 0 09:24:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:24:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:24:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:24:48 INFO - Return code: 0 09:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:24:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bPVeLawLed/Render', 09:24:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:48 INFO - 'HOME': '/Users/cltbld', 09:24:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:48 INFO - 'LOGNAME': 'cltbld', 09:24:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:48 INFO - 'MOZ_NO_REMOTE': '1', 09:24:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:48 INFO - 'NO_EM_RESTART': '1', 09:24:48 INFO - 'PAGER': '/bin/cat', 09:24:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:48 INFO - 'PWD': '/builds/slave/test', 09:24:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:48 INFO - 'SHELL': '/bin/bash', 09:24:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.laLCoKwyqF/Listeners', 09:24:48 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:24:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:48 INFO - 'USER': 'cltbld', 09:24:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:48 INFO - 'XPC_FLAGS': '0x0', 09:24:48 INFO - 'XPC_SERVICE_NAME': '0', 09:24:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:24:48 INFO - mozversion application_buildid: 20160621081102 09:24:48 INFO - mozversion application_changeset: fb8483f7bc37750e0bf19549141c922d37262c3d 09:24:48 INFO - mozversion application_display_name: Firefox 09:24:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:48 INFO - mozversion application_name: Firefox 09:24:48 INFO - mozversion application_remotingname: firefox 09:24:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:24:48 INFO - mozversion application_vendor: Mozilla 09:24:48 INFO - mozversion application_version: 48.0 09:24:48 INFO - mozversion platform_buildid: 20160621081102 09:24:48 INFO - mozversion platform_changeset: fb8483f7bc37750e0bf19549141c922d37262c3d 09:24:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:24:48 INFO - mozversion platform_version: 48.0 09:24:48 INFO - using testdate: 1466526288 09:24:48 INFO - actual date: 1466526288 09:24:48 INFO - starting webserver on 'localhost:49275' 09:24:48 INFO - SUITE-START | Running 2 tests 09:24:48 INFO - TEST-START | dromaeo_css 09:24:48 INFO - operating with platform_type : mac_ 09:24:48 INFO - Initialising browser for dromaeo_css test... 09:24:48 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpfPEHXX/profile http://localhost:49275/getInfo.html) 09:24:51 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:24:51 INFO - PROCESS | 1663 | colorDepth:24 09:24:51 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:24:51 INFO - PROCESS | 1663 | __metrics 09:24:51 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 09:24:52 INFO - TEST-INFO | 1663: exit 0 09:24:52 INFO - Browser initialized. 09:24:52 INFO - Running cycle 1/1 for dromaeo_css test... 09:24:52 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpfPEHXX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:04 INFO - PROCESS | 1668 | RSS: Main: 170799104 09:25:04 INFO - PROCESS | 1668 | 09:25:53 INFO - PROCESS | 1668 | 1466526353857 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:28:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 09:28:04 INFO - PROCESS | 1668 | RSS: Main: 182022144 09:28:04 INFO - PROCESS | 1668 | 09:31:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:31:07 INFO - PROCESS | 1668 | RSS: Main: 192655360 09:31:07 INFO - PROCESS | 1668 | 09:34:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:34:09 INFO - PROCESS | 1668 | RSS: Main: 194482176 09:34:09 INFO - PROCESS | 1668 | 09:37:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:37:12 INFO - PROCESS | 1668 | RSS: Main: 198320128 09:37:12 INFO - PROCESS | 1668 | 09:40:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:40:15 INFO - PROCESS | 1668 | RSS: Main: 197500928 09:40:15 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:43:17 INFO - PROCESS | 1668 | RSS: Main: 202268672 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | __start_tp_report 09:43:17 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 09:43:17 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 09:43:17 INFO - PROCESS | 1668 | |i|pagename|runs| 09:43:17 INFO - PROCESS | 1668 | |0;dojo.html;2988.716071973162;3570.089627145078;3616.6925811306037;3684.5025921500587;3697.057250401149;4228.97885510572;4689.835855745049;5485.8479719259;5753.1370294455855;5774.047282198438;4721.291806229067;5733.226014488043;5789.507891829199;5800.709964501779;5811.970940145294;5207.401148867891;5243.292155558998;5246.921296180561;5307.416184219733;5325.813596524123;4480.97759511202;4596.092621562065;5084.745762711868;5199.142141546659;5320.76056577453;5279.155335146377;5309.8938021239555;5312.814051508198;5314.432242488475;5328.493793089669;5016.247562865563;5277.947220527784;5287.4448182941005;5328.413874473844;5364.1149210380245;5008.84973450797;5107.693538387709;5173.379194496684;5215.58275337972;5227.137522308814;4671.743054132022;5224.138017227153;5575.303087114111;5627.493525582717;5637.126245431965;6165.907511387333;6317.842053948642;6322.557420980534;6326.08271800591;6350.110984462179;6171.074338849164;6181.6600086995195;6267.2792628847355;6303.117563541108;6327.778527751552;5846.766129354821;5871.089981052944;5890.14592884038;5895.911561326584;5907.734151963172;6220.471259942956;6266.843328916722;6295.213098362705;6305.274893387227;6316.905246421307;6233.501319894398;6238.812835614939;6242.282137554149;6264.436200741955;6266.216722909637;6313;6315.463185629181;6327.209098862642;6334.461570766536;6335.936640633535;6152.507799376039;6241.438270555672;6289.559730818799;6297.307296197415;6314.305426403092;5665.49010589049;5892.9116063258225;6305.905411418832;6318.968405157945;6335.841603959846;4993.450720420751;5036.104240061979;5045.192769156991;5047.268146118792;5058.013687330935;4472.195004899084;4493.348464472699;4547.136044151601;4566.840160594364;4613.123506533748;5445.883593863248;5676.602637815313;5885.5585831062845;6050.546209034252;6123.252118380239;6264.310925798159;6282.842928926813;6283.842903927439;6294.685265736694;6298.118263443122;5958.612690175125;6064.636121832704;6088.786892458743;6128.044828652092;6152.169457123231;5519.227308176858;5538.833834984957;5541.833744987657;5557;5569.498745112959;6610.438112760373;6618.8345291368105;6625.536212465177;6631.767888123893;6641.302663220292;6189.288231853395;6210.223722034746;6281.96859015702;6298.370162983665;6326.240851097898;6559;6606.207255129415;6609.272979972103;6634.104395906491;6635.838728222445;5021.970496048374;6133.2428548619655;6222.906656400248;6300.9684951574945;6301.6219026858535;6215.347388524321;6231.22109736283;6272.059191121186;6319.557630965972;6323.905141422974;6026.457618814327;6216.751329946752;6225.62646241227;6238.500919926304;6249.531285153541;6065.969670151621;6083.117947897618;6186.288576813544;6221.626702397871;6225.408586184305;5980.730867110903;6035.818925432244;6037.275526936795;6044.576879618385;6057.757689692539;6100.328963813889;6151.200343955436;6183.072539119168;6183.721732522137;6185.443310102112;5949.583529152917;6015.909761353496;6034.7284372202475;6048.637081774931;6063.454289114001 09:43:17 INFO - PROCESS | 1668 | |1;ext.html;534.3934634190194;545.487241992527;579.733322671571;581.444720292121;588.4078161088097;38755.806220968894;39752.80123599385;39830.601693983015;39848.402273965876;39911.60309389172;43192.704218873485;43843.90390240245;43938.34092488615;44026.11947761043;44033.55966440334;9624.518774061322;9730.65942692006;9743.123118919295;9765.511724413787;9889.456079915602;9712.320137590372;9792.971737967518;9817.901820981806;9825.164860986824;9838.803223935553;9795.510224488748;9871.555779989918;9874.407535547854;9879.703608891745;9886.307958442912;9743.123118919331;9754.756131096743;9823.95088024559;9839.917609062997;9856.90143098567;9713.174380177623;9794.706158815246;9817.460039697813;9817.460039697813;9837.114659680663;33389;39429.01427464308;39456.60543394558;39915.60084399148;40183.7990810047;3190.1865024418858;3347.581552305962;3350.463925771889;3880.282147802637;3988.900277493086;26380.340491487867;26556.60165097525;27390;27750.861245693643;28146.577801333;2772.487089888377;2779.43021680555;2783.6102945587636;2797.2727090956296;2998.5502174673975;729524;751589;754051;755206.2239688821;758454;53868;63957.04064439037;64153;64702.352976470575;64791.02813457802;71024.2897571028;71700.64149679219;72399;72483.91274130892;72787.63606181936;18760.71858922117;18898.433047008613;18905.527361815788;18920.905395472935;18958;14283.78574321386;14307.856921430652;14782.48261310849;14787.92606036963;14853.777193342108;16951.576210594834;18521.629567408578;20346.796532034787;20522.281720139723;20593.48516287105;7717.305442510089;8783.472991620523;8827.382083254111;8911.866322005175;8990.168097049409;932.1563984593953;974.8147851908114;1058.9576416943237;1105.132471010253;1204.9031898593146;29271.560926586117;30175;30532.68861892608;30649.693503064686;31190.532142017888;1078.336822853939;1125.471028616549;1214.2957084890743;1238.4427007846505;1243.800991841319;13960.86039139616;15501.922490387477;15778.526644200692;15795.499427554363;16977.89643673157;649588;806214.9689251634;808117;815005;818616;59792.40207597955;59950;60546;60549;60722.089168662504;1576.9526914192593;1583.9841601583837;1587.6904003719164;1589.8569128778233;1592.5302035899206;28743;30780.23049423782;31079.378412432077;31115.84442077775;31277.218069547533;52079.95840083101;52431.73784131055;53147.46852531425;53766;53849;598415;599056;605932;606730;607779;27042.188734338;28055.579166312113;28292.717072829008;28455;28527.572086418306;45613.77193114013;45677.314840276755;46190;46821.53178468172;47341.28988065113;37978.24043519169;38028.42957355697;38263;38330.23339533249;38513.80743096267;37630.059248519006;37655.81172094122;38077.809610951765;38171.42742858804;38328.80835595804;36502.72240471571;37173;37872;38441.231175376895;38598.4210236841;31699.35457422426;32378.514322284715;32782.836085820374;33013.00960971175;33192.502112467846;21756.023302207494;24911;25515.36211594725;26502.939882404487;26991 09:43:17 INFO - PROCESS | 1668 | |2;jquery.html;382.72826293331735;413.89238797912543;414.69934297634217;417.8892593462731;425.6573458366016;18253.817461825376;20851.687224691625;21439.820809855453;21607;21624.89187554062;20722.585548289062;21236.57526849462;21326.46683832905;21341;21361.465963350925;5686.175504551838;5942.316633587139;5972.0444728843395;5994.43052909975;5996.520278377741;5905.31627463445;5928.081147422135;5960.672163031031;6642.701078451457;6852.6231057291825;6776.288489708559;6781.457483401341;6802.591844489347;6803.557768745016;6821.693023813916;5987.401259873999;6152.323244443108;6190.102435146923;6246.937530624704;6752.426043786284;5858.326292476335;5941.732622032019;5943.64338139713;5977.98374276374;5984.700764961778;25329.860156292827;25406.36484087894;25884.611730824054;26255.474890502275;26313.342166445993;2204.647256438962;2211.3365990202874;2315.2591170825344;2354.8940297686645;2665.2803742989477;8415.410921235516;9190.494522801244;9771.804563908752;10327.741806454824;11205.21563490556;1712.8030276518236;1812.9909350453295;1843.023197705205;1864.3567969050791;1896.4121122452084;92185;110518;111388;117044.99999999914;117404;11766.647000589996;11973.820392693942;11976.28142311464;12014;12069.27584344942;12865.228086314852;13950;15001.849981500263;15360.617544420833;15509.457168999032;8405.949256342958;9160.587773550073;9232.261419086457;9398.859017114748;9407.341486095907;9274.21169200612;9499.525023748785;9521.523923803783;9528.475933823705;9569.904300957041;4705.60002399793;4762.738049407316;4791.425028996473;4811.855644330676;4851.80592776285;2009.5277609761695;2100.7479102507796;2134.4983928776724;2139.6041732279577;2142.421546182522;924.9445033298024;975.6438899801522;985.6599473181741;991.6330957545754;1021.4126877045728;20039.198432062847;20309.289174879104;20413.69379459309;20574.58850822975;20657;836.3560058754725;977.8533220016913;1008.1430783833829;1017.4505766885799;1030.6083688198437;10751;10957.83563246552;10982.176336774774;10998.780024399468;11009.009189172853;628485;638581;640344;645638;669717;120250.3987480057;121081;124319.75680243263;128329;129205.35397322953;1029.6602121299952;1034.2811745836723;1035.4667346316653;1039.7452624107143;1041.1098510773331;17833.375831845824;17954.371596994042;18134.183961722018;18224.72662910031;18282.634347313244;40536;48980.75509622429;49865.75335616639;52484.687882803235;55668.608284793205;70842.64578677074;71814.92277615944;72340;72849;72851;8035.477693949874;8091.393145514227;8260.091389946982;8414.87377689323;11491.965723084628;12917.09580329368;13187.076904616584;13471.989600779785;13486.12340197884;13856;9515.238780897651;10669.46652667379;11162.665120046411;11862;12138.817917731418;5358.678479291255;8568.914310856811;11469.254498457574;11496.103142981314;11641.6507504775;11952.462139203582;11990.640280791591;12061.51753929833;12116.21244619097;12165.939170304091;11616.593419230287;12102;12620.684482888002;12647.430865611253;12753.553625623059;11751.118666099906;11892.583759568373;11964.58123965657;11992.160548761503;12017.939910300393 09:43:17 INFO - PROCESS | 1668 | |3;mootools.html;1503.8947273690842;1571.3400371843825;1890.725844752511;1996.4908948218201;2043.7343145391096;6550.836229094275;8186.386021048415;8760.737177884657;8972;9493.386124358858;8850.33622478315;8885.689000884955;8915.375923685344;8957.865632015524;8981.101889811016;2512.610545365467;2513.987430062847;2522.545941730488;2531.354504601324;2532.658091157698;2267.342470683508;2482.9751702483013;2539.21284401836;2540.7586279303528;2558.48830233953;2496.750324967498;2504.7745702886737;2525.936851578707;2551.5152121097026;2563.1797824696105;2204.1954686539457;2514.421683012899;2542.377117606198;2544.643749875019;2563.0772921748157;2509.0591028364365;2524.7222805491383;2537.0612873236837;2553.885075171622;2576.2271318604344;7922.04935407749;9000.77498062547;9005.279577633839;9010.774730631721;9018.774530636721;5580.972095139539;5597.3003374578175;5607.130894711286;5631.211630371751;5668.943310566883;3639.253952939641;3764.134249122717;3767.4913886625227;3787.110029143149;3808.352580061396;1077.5151181968067;1081.1080858291941;1101.3502033800016;1106.2753896198003;1110.900018998286;12252.816207756801;12516.937415312865;12532;12579.433925473375;12584.24494530322;4999.650024498323;5039.294498770175;5051.747412629354;5114;5127.4872512748425;10364.429956352473;10575.524101415454;10613.9469302653;10649.520771565298;10653.467326633638;4806.567408933212;5566.192902029183;5608.130739735266;5613.943860561342;5620.353659329147;4628.722276663411;5076.6192535559985;5103.055934652101;5134.409542902539;5194.22086687;4206.978965105216;4224.471941007374;4247.384129301234;4269.487661480643;4271.572842715766;1401.8878489721;1425.4939496478808;1427.093795439903;1428.4928850258216;1443.81952255968;926.935114541989;944.7968686732386;951.7144856543009;960.1406740966795;992.5831150916632;6043.969780151071;6057.091436284592;6158.107074474266;6197.4732147767945;6234.376562343729;485.43204450792655;488.6896820518925;492.3918960084293;1044.7074819050679;1046.5552140340326;4041.777702226347;4098;4117.732347397409;4133.318002529549;4168.874933751992;10479.056884880398;10774.030337269684;10826.404547749791;10896.291741036806;10965.780684386267;10464.843027354596;10614.787704246026;10682;10723.94638026805;10741.462926853626;7044.401225895754;8348.373871959628;8486.915130848736;8526.78683032929;8582.270507006975;5760.279965004374;6041.909371359521;6058.969705151446;6070.7875224366935;6084.026555751058;9629.662961796303;9803.16673082796;9857.704268871945;9858.162056225014;9917.15704165154;9926.503674816086;10143.34068285559;10145.64490242838;10395.220358473296;10440.008199221062;6998.230194678479;7436;7711.305982461606;7793.415493838098;7945;8436.493810371398;8477;8524.27543658796;8541.91458085436;8559.058503564478;7490.475666703496;7625.618719064136;7661.233876612294;7722.845543088994;7733.961330193313;6250.718717657625;7485.513441626277;7547.433942454228;7562.395008399425;7657.851322301699;7230.349268565854;7240.818979525554;7282.0533330668795;7300.96349518249;7303.561786292839;4965.503449655005;4973.45292017885;5036.8992620146655;5047.6214283929585;5168.328117344721;4593.28804035375;4783.545563171493;5019.774110164978;5187.714675692873;5214.426413094632 09:43:17 INFO - PROCESS | 1668 | |4;prototype.html;340.5351694936411;341.375283231686;341.6702881719142;347.5117459968744;352.922357081442;6718.496112791542;6739.9663001684985;6834.760783372583;6838.213605435377;6881.690323935422;6733.293004234558;6739.6411256060355;6744.2244141923575;6753.358430949064;6759.053732477445;4792.904141917168;4890.6576539642065;5024.120778863702;5031.622628302874;5034.219695947116;4956.40523137224;4978.850634480971;5004.099262132815;5015.297858299841;5027.8491645250515;4941.555260026596;4958.305837182797;4965.354503914504;5000.874978125539;5030.547250747432;4688.929666055012;5028.547430731215;5035.597152227831;5039.899202015976;5043.949560504385;4334.978325108385;4336.8265269389185;4343.543927887558;4344.847930322424;4358.607725304706;6203.47270482005;6468.062130991027;6500.544961852672;6506.739730410778;6516.739330426777;5807.332156801979;5828.2806572061345;5849.064149736065;5854.443827836349;5883.441073098049;6359.109724638554;6675.065490831287;7233.45749068822;7238.167610724729;7264.309890560388;4706.1175529788015;4708.693934894221;4711.693739906895;4714.057188562267;4736.739479328636;7322.231165727629;7489.475736698433;7578.886316705199;7584.582847943361;7605.885911711329;4761.999980004238;4774.21225497789;4782.545658162469;4791.976040119846;4796.5203479652455;4810.542998415145;4842.322074909515;4876.5854902332985;4876.829310974099;4890.266460031024;5795.043817770105;5801.564882633735;5802.47777700009;5823.56323275756;5824.15549745293;7251.166115896739;7258.564486130849;7260.2013778484325;7263.963680181671;7268.87332463469;1660.5350501859498;1680.5378520906604;1681.201429321087;1681.7645529625863;1682.9061110278417;1037.7509397744486;1069.2301542889263;1075.1184029096066;1076.8976947190004;1082.826747720361;5593.804216852391;6958.234594194634;6978.895316570255;6992.860142797115;7017.614031228331;7305.707771689179;7385.3722433592675;7419.851602968019;7486.663100160506;7487.1764105948305;7080.964595176991;7093.538919970186;7101.680424380916;7111.288871112847;7134.643267836691;3258.625258095349;3964.920701585952;4005.399190121505;4011.5587285398583;4019.4171845082883;6872.62200578963;8144.104148543655;8156.551389673625;8159.428839981263;8235.752927412188;7595.658195381221;7751.1861254568585;7761.301482866569;7832.177621349791;7876.72431464896;8099.230573095547;8138.877916831252;8140.226678465537;8142.470739402039;8173.959130204311;7536.962315188389;7557.924420755722;7558.470907036674;7564.735234266775;7574.545527268381;6626.503012274003;6655.434288085373;7477.065366829146;7479.850402991801;7484.663190156347;6220.440160385406;8122.309603683517;8128.390370722102;8139.75580732579;8151.184881511801;4137.731047481904;4767.904641907073;4794.808207671654;4801.423829140525;4807.6394270429155;7532.585707786125;7533.623318834146;7544.358729508053;7553.395728341609;7581.583012934121;6487.54587178893;6617.602943823386;7204.639768011473;7235.095613048369;7271.163816160997;6969.233384327618;7268.563886166847;7280.599567023864;7281.599512026677;7290.198078211286;5634.295713035871;7086.291370863079;7105.46708996817;7121.893171602534;7128.037714908628;7252.166000909963;7267.236940121423;7273.418126549969;7280.0535930328715;7285.490015698851;6547.690461907733;6891.586504809728;7116.822079448055;7117.644117794193;7121.14546254432 09:43:17 INFO - PROCESS | 1668 | |5;yui.html;684.2131548718972;699.8075529229461;703.9651711983382;710.7015053677455;784.6547519091599;15293;15462.07227566344;15478.38086476541;15601.375944962188;15643.608909777233;14357.138571685706;14395.712085758278;14409.279536023208;14465.05977111487;16010.919945400257;960.3661583354988;961.341481085457;981.8429051351785;982.8427451607744;983.0955520920734;795.6181033104114;970.7621632699999;971.9076687714655;976.4190306767462;976.9609215631366;1000.5147503460815;1014.822406078937;1015.1979935850669;1022.8874823769416;1023.4422239879284;966.4104896013426;966.9613215471372;1020.4693559349134;1024.636254129788;1027.2193133218732;722.3390597603187;764.8470305938835;770.6108415250319;771.3597713897452;772.9884051739228;15411.22943852803;15640.139792311418;15721.685566288725;15736;15737.134457604827;12544.937275313656;12573.308468034254;12736.36318184087;12746.617601471971;12865.807012894815;10252.475247524753;11235.269707468988;11473.713157171054;11558.884411155865;11599.420028998516;3558.893233202982;3578.087587665155;3599.4600809878466;3666.046827824758;3705.7035437164964;84290.57854710687;84996;85297.57351213266;85758;86525;8310.335173186131;8529.74410767678;9333.533323333806;9586.760330991781;9670.806583868283;21027.8948605256;21551.35345939593;21651.78348216529;21745.891270543547;21777.455563611038;7429;7594.468387212842;7654.043244594426;7733.574653394005;7767.650455729506;4086.3461846105074;4167.729097608646;4212.789360531961;4283.507396649363;4340.414044104069;3750.3061933541835;3751.118487155516;3756.417755247941;3823.1206822430995;3849.8652547160714;682.0383259603934;683.5830143612393;687.2508965227926;688.277308825731;688.3151264491862;503.64996327552734;537.8865747901806;594.7650677982173;625.3308959413384;650.6226388694546;7940.483868548641;8518.190771876662;8569.014563325298;8582.613782379807;8717.782055448537;521.3743507790666;529.6319058254476;552.7706002009121;573.8364566098726;625.8873402787451;3195.3130077033547;3816.4847745553993;3908.863189788344;3985.163115745726;3989.5412027616617;86775.56612216898;86880;87486;97307.51346243224;98536;76128;76466;76698.61650691711;77404;78190.60904695553;71916.92124618134;73124.2687573128;73303.26696732965;73557.26442735612;74053.88919166217;6998.6150761708595;8582.541225964043;8595.355348349023;8650.394472386872;8654.177853103945;47095.529044710485;47472.81317967078;47484;48131.03737925292;48863.755681221366;51988;52260.477395225564;64354.678226608565;64500.677496614095;64588.67705661442;11605.88394116048;11610.94194529022;11787.469563869818;11976.221545599508;12081.395930203631;11731;12105;12201.08491863096;12304.753904921672;12541.184822986477;11037.448127593749;11515.53937842477;11639.349150655777;12046.337451439904;12122.33327166979;11485;11583.131265154978;11756;11846.644600661994;12099.81850272229;11181.832272516082;11315.377654229096;11369.658910232707;11553.422328883691;11723.296602203895;9866.210703143588;9950.502474876372;9969.202463803023;9995.950020249853;10100.393976361147;9633.277504187074;9648.903510965081;9693.515324233902;9761.755956101155;9824 09:43:17 INFO - PROCESS | 1668 | __end_tp_report 09:43:17 INFO - PROCESS | 1668 | __start_cc_report 09:43:17 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,394 09:43:17 INFO - PROCESS | 1668 | __end_cc_report 09:43:17 INFO - PROCESS | 1668 | __startTimestamp1466527397725__endTimestamp 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | ------- Summary: start ------- 09:43:17 INFO - PROCESS | 1668 | Number of tests: 6 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5782.49 Median:6057.76 stddev:688.15 (11.4%) stddev-sans-first:654.43 09:43:17 INFO - PROCESS | 1668 | Values: 2988.7 3570.1 3616.7 3684.5 3697.1 4229.0 4689.8 5485.8 5753.1 5774.0 4721.3 5733.2 5789.5 5800.7 5812.0 5207.4 5243.3 5246.9 5307.4 5325.8 4481.0 4596.1 5084.7 5199.1 5320.8 5279.2 5309.9 5312.8 5314.4 5328.5 5016.2 5277.9 5287.4 5328.4 5364.1 5008.8 5107.7 5173.4 5215.6 5227.1 4671.7 5224.1 5575.3 5627.5 5637.1 6165.9 6317.8 6322.6 6326.1 6350.1 6171.1 6181.7 6267.3 6303.1 6327.8 5846.8 5871.1 5890.1 5895.9 5907.7 6220.5 6266.8 6295.2 6305.3 6316.9 6233.5 6238.8 6242.3 6264.4 6266.2 6313.0 6315.5 6327.2 6334.5 6335.9 6152.5 6241.4 6289.6 6297.3 6314.3 5665.5 5892.9 6305.9 6319.0 6335.8 4993.5 5036.1 5045.2 5047.3 5058.0 4472.2 4493.3 4547.1 4566.8 4613.1 5445.9 5676.6 5885.6 6050.5 6123.3 6264.3 6282.8 6283.8 6294.7 6298.1 5958.6 6064.6 6088.8 6128.0 6152.2 5519.2 5538.8 5541.8 5557.0 5569.5 6610.4 6618.8 6625.5 6631.8 6641.3 6189.3 6210.2 6282.0 6298.4 6326.2 6559.0 6606.2 6609.3 6634.1 6635.8 5022.0 6133.2 6222.9 6301.0 6301.6 6215.3 6231.2 6272.1 6319.6 6323.9 6026.5 6216.8 6225.6 6238.5 6249.5 6066.0 6083.1 6186.3 6221.6 6225.4 5980.7 6035.8 6037.3 6044.6 6057.8 6100.3 6151.2 6183.1 6183.7 6185.4 5949.6 6015.9 6034.7 6048.6 6063.5 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:82602.01 Median:39604.70 stddev:192505.10 (486.1%) stddev-sans-first:192946.52 09:43:17 INFO - PROCESS | 1668 | Values: 534.4 545.5 579.7 581.4 588.4 38755.8 39752.8 39830.6 39848.4 39911.6 43192.7 43843.9 43938.3 44026.1 44033.6 9624.5 9730.7 9743.1 9765.5 9889.5 9712.3 9793.0 9817.9 9825.2 9838.8 9795.5 9871.6 9874.4 9879.7 9886.3 9743.1 9754.8 9824.0 9839.9 9856.9 9713.2 9794.7 9817.5 9817.5 9837.1 33389.0 39429.0 39456.6 39915.6 40183.8 3190.2 3347.6 3350.5 3880.3 3988.9 26380.3 26556.6 27390.0 27750.9 28146.6 2772.5 2779.4 2783.6 2797.3 2998.6 729524.0 751589.0 754051.0 755206.2 758454.0 53868.0 63957.0 64153.0 64702.4 64791.0 71024.3 71700.6 72399.0 72483.9 72787.6 18760.7 18898.4 18905.5 18920.9 18958.0 14283.8 14307.9 14782.5 14787.9 14853.8 16951.6 18521.6 20346.8 20522.3 20593.5 7717.3 8783.5 8827.4 8911.9 8990.2 932.2 974.8 1059.0 1105.1 1204.9 29271.6 30175.0 30532.7 30649.7 31190.5 1078.3 1125.5 1214.3 1238.4 1243.8 13960.9 15501.9 15778.5 15795.5 16977.9 649588.0 806215.0 808117.0 815005.0 818616.0 59792.4 59950.0 60546.0 60549.0 60722.1 1577.0 1584.0 1587.7 1589.9 1592.5 28743.0 30780.2 31079.4 31115.8 31277.2 52080.0 52431.7 53147.5 53766.0 53849.0 598415.0 599056.0 605932.0 606730.0 607779.0 27042.2 28055.6 28292.7 28455.0 28527.6 45613.8 45677.3 46190.0 46821.5 47341.3 37978.2 38028.4 38263.0 38330.2 38513.8 37630.1 37655.8 38077.8 38171.4 38328.8 36502.7 37173.0 37872.0 38441.2 38598.4 31699.4 32378.5 32782.8 33013.0 33192.5 21756.0 24911.0 25515.4 26502.9 26991.0 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:36045.42 Median:21523.41 stddev:106808.59 (496.2%) stddev-sans-first:107074.65 09:43:17 INFO - PROCESS | 1668 | Values: 382.7 413.9 414.7 417.9 425.7 18253.8 20851.7 21439.8 21607.0 21624.9 20722.6 21236.6 21326.5 21341.0 21361.5 5686.2 5942.3 5972.0 5994.4 5996.5 5905.3 5928.1 5960.7 6642.7 6852.6 6776.3 6781.5 6802.6 6803.6 6821.7 5987.4 6152.3 6190.1 6246.9 6752.4 5858.3 5941.7 5943.6 5978.0 5984.7 25329.9 25406.4 25884.6 26255.5 26313.3 2204.6 2211.3 2315.3 2354.9 2665.3 8415.4 9190.5 9771.8 10327.7 11205.2 1712.8 1813.0 1843.0 1864.4 1896.4 92185.0 110518.0 111388.0 117045.0 117404.0 11766.6 11973.8 11976.3 12014.0 12069.3 12865.2 13950.0 15001.8 15360.6 15509.5 8405.9 9160.6 9232.3 9398.9 9407.3 9274.2 9499.5 9521.5 9528.5 9569.9 4705.6 4762.7 4791.4 4811.9 4851.8 2009.5 2100.7 2134.5 2139.6 2142.4 924.9 975.6 985.7 991.6 1021.4 20039.2 20309.3 20413.7 20574.6 20657.0 836.4 977.9 1008.1 1017.5 1030.6 10751.0 10957.8 10982.2 10998.8 11009.0 628485.0 638581.0 640344.0 645638.0 669717.0 120250.4 121081.0 124319.8 128329.0 129205.4 1029.7 1034.3 1035.5 1039.7 1041.1 17833.4 17954.4 18134.2 18224.7 18282.6 40536.0 48980.8 49865.8 52484.7 55668.6 70842.6 71814.9 72340.0 72849.0 72851.0 8035.5 8091.4 8260.1 8414.9 11492.0 12917.1 13187.1 13472.0 13486.1 13856.0 9515.2 10669.5 11162.7 11862.0 12138.8 5358.7 8568.9 11469.3 11496.1 11641.7 11952.5 11990.6 12061.5 12116.2 12165.9 11616.6 12102.0 12620.7 12647.4 12753.6 11751.1 11892.6 11964.6 11992.2 12017.9 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5862.76 Median:5009.71 stddev:3241.86 (64.7%) stddev-sans-first:3234.41 09:43:17 INFO - PROCESS | 1668 | Values: 1503.9 1571.3 1890.7 1996.5 2043.7 6550.8 8186.4 8760.7 8972.0 9493.4 8850.3 8885.7 8915.4 8957.9 8981.1 2512.6 2514.0 2522.5 2531.4 2532.7 2267.3 2483.0 2539.2 2540.8 2558.5 2496.8 2504.8 2525.9 2551.5 2563.2 2204.2 2514.4 2542.4 2544.6 2563.1 2509.1 2524.7 2537.1 2553.9 2576.2 7922.0 9000.8 9005.3 9010.8 9018.8 5581.0 5597.3 5607.1 5631.2 5668.9 3639.3 3764.1 3767.5 3787.1 3808.4 1077.5 1081.1 1101.4 1106.3 1110.9 12252.8 12516.9 12532.0 12579.4 12584.2 4999.7 5039.3 5051.7 5114.0 5127.5 10364.4 10575.5 10613.9 10649.5 10653.5 4806.6 5566.2 5608.1 5613.9 5620.4 4628.7 5076.6 5103.1 5134.4 5194.2 4207.0 4224.5 4247.4 4269.5 4271.6 1401.9 1425.5 1427.1 1428.5 1443.8 926.9 944.8 951.7 960.1 992.6 6044.0 6057.1 6158.1 6197.5 6234.4 485.4 488.7 492.4 1044.7 1046.6 4041.8 4098.0 4117.7 4133.3 4168.9 10479.1 10774.0 10826.4 10896.3 10965.8 10464.8 10614.8 10682.0 10723.9 10741.5 7044.4 8348.4 8486.9 8526.8 8582.3 5760.3 6041.9 6059.0 6070.8 6084.0 9629.7 9803.2 9857.7 9858.2 9917.2 9926.5 10143.3 10145.6 10395.2 10440.0 6998.2 7436.0 7711.3 7793.4 7945.0 8436.5 8477.0 8524.3 8541.9 8559.1 7490.5 7625.6 7661.2 7722.8 7734.0 6250.7 7485.5 7547.4 7562.4 7657.9 7230.3 7240.8 7282.1 7301.0 7303.6 4965.5 4973.5 5036.9 5047.6 5168.3 4593.3 4783.5 5019.8 5187.7 5214.4 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5940.34 Median:6725.89 stddev:1913.21 (28.4%) stddev-sans-first:1871.84 09:43:17 INFO - PROCESS | 1668 | Values: 340.5 341.4 341.7 347.5 352.9 6718.5 6740.0 6834.8 6838.2 6881.7 6733.3 6739.6 6744.2 6753.4 6759.1 4792.9 4890.7 5024.1 5031.6 5034.2 4956.4 4978.9 5004.1 5015.3 5027.8 4941.6 4958.3 4965.4 5000.9 5030.5 4688.9 5028.5 5035.6 5039.9 5043.9 4335.0 4336.8 4343.5 4344.8 4358.6 6203.5 6468.1 6500.5 6506.7 6516.7 5807.3 5828.3 5849.1 5854.4 5883.4 6359.1 6675.1 7233.5 7238.2 7264.3 4706.1 4708.7 4711.7 4714.1 4736.7 7322.2 7489.5 7578.9 7584.6 7605.9 4762.0 4774.2 4782.5 4792.0 4796.5 4810.5 4842.3 4876.6 4876.8 4890.3 5795.0 5801.6 5802.5 5823.6 5824.2 7251.2 7258.6 7260.2 7264.0 7268.9 1660.5 1680.5 1681.2 1681.8 1682.9 1037.8 1069.2 1075.1 1076.9 1082.8 5593.8 6958.2 6978.9 6992.9 7017.6 7305.7 7385.4 7419.9 7486.7 7487.2 7081.0 7093.5 7101.7 7111.3 7134.6 3258.6 3964.9 4005.4 4011.6 4019.4 6872.6 8144.1 8156.6 8159.4 8235.8 7595.7 7751.2 7761.3 7832.2 7876.7 8099.2 8138.9 8140.2 8142.5 8174.0 7537.0 7557.9 7558.5 7564.7 7574.5 6626.5 6655.4 7477.1 7479.9 7484.7 6220.4 8122.3 8128.4 8139.8 8151.2 4137.7 4767.9 4794.8 4801.4 4807.6 7532.6 7533.6 7544.4 7553.4 7581.6 6487.5 6617.6 7204.6 7235.1 7271.2 6969.2 7268.6 7280.6 7281.6 7290.2 5634.3 7086.3 7105.5 7121.9 7128.0 7252.2 7267.2 7273.4 7280.1 7285.5 6547.7 6891.6 7116.8 7117.6 7121.1 09:43:17 INFO - PROCESS | 1668 | 09:43:17 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18341.23 Median:26254.69 stddev:25566.80 (97.4%) stddev-sans-first:25604.14 09:43:17 INFO - PROCESS | 1668 | Values: 684.2 699.8 704.0 710.7 784.7 15293.0 15462.1 15478.4 15601.4 15643.6 14357.1 14395.7 14409.3 14465.1 16010.9 960.4 961.3 981.8 982.8 983.1 795.6 970.8 971.9 976.4 977.0 1000.5 1014.8 1015.2 1022.9 1023.4 966.4 967.0 1020.5 1024.6 1027.2 722.3 764.8 770.6 771.4 773.0 15411.2 15640.1 15721.7 15736.0 15737.1 12544.9 12573.3 12736.4 12746.6 12865.8 10252.5 11235.3 11473.7 11558.9 11599.4 3558.9 3578.1 3599.5 3666.0 3705.7 84290.6 84996.0 85297.6 85758.0 86525.0 8310.3 8529.7 9333.5 9586.8 9670.8 21027.9 21551.4 21651.8 21745.9 21777.5 7429.0 7594.5 7654.0 7733.6 7767.7 4086.3 4167.7 4212.8 4283.5 4340.4 3750.3 3751.1 3756.4 3823.1 3849.9 682.0 683.6 687.3 688.3 688.3 503.6 537.9 594.8 625.3 650.6 7940.5 8518.2 8569.0 8582.6 8717.8 521.4 529.6 552.8 573.8 625.9 3195.3 3816.5 3908.9 3985.2 3989.5 86775.6 86880.0 87486.0 97307.5 98536.0 76128.0 76466.0 76698.6 77404.0 78190.6 71916.9 73124.3 73303.3 73557.3 74053.9 6998.6 8582.5 8595.4 8650.4 8654.2 47095.5 47472.8 47484.0 48131.0 48863.8 51988.0 52260.5 64354.7 64500.7 64588.7 11605.9 11610.9 11787.5 11976.2 12081.4 11731.0 12105.0 12201.1 12304.8 12541.2 11037.4 11515.5 11639.3 12046.3 12122.3 11485.0 11583.1 11756.0 11846.6 12099.8 11181.8 11315.4 11369.7 11553.4 11723.3 9866.2 9950.5 9969.2 9996.0 10100.4 9633.3 9648.9 9693.5 9761.8 9824.0 09:43:17 INFO - PROCESS | 1668 | -------- Summary: end -------- 09:43:17 INFO - PROCESS | 1668 | 09:43:18 INFO - TEST-INFO | 1668: exit 0 09:43:18 INFO - TEST-OK | dromaeo_css | took 1109708ms 09:43:18 INFO - TEST-START | kraken 09:43:18 INFO - operating with platform_type : mac_ 09:43:18 INFO - Initialising browser for kraken test... 09:43:18 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpDJKPBK/profile http://localhost:49275/getInfo.html) 09:43:20 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 09:43:20 INFO - PROCESS | 1677 | colorDepth:24 09:43:20 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 09:43:20 INFO - PROCESS | 1677 | __metrics 09:43:20 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 09:43:21 INFO - TEST-INFO | 1677: exit 0 09:43:21 INFO - Browser initialized. 09:43:21 INFO - Running cycle 1/1 for kraken test... 09:43:21 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpDJKPBK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:33 INFO - PROCESS | 1681 | RSS: Main: 171130880 09:43:33 INFO - PROCESS | 1681 | 09:43:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:37 INFO - PROCESS | 1681 | RSS: Main: 183701504 09:43:37 INFO - PROCESS | 1681 | 09:43:39 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:43:40 INFO - PROCESS | 1681 | RSS: Main: 192495616 09:43:40 INFO - PROCESS | 1681 | 09:43:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:43:44 INFO - PROCESS | 1681 | RSS: Main: 193699840 09:43:44 INFO - PROCESS | 1681 | 09:43:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:43:47 INFO - PROCESS | 1681 | RSS: Main: 197259264 09:43:47 INFO - PROCESS | 1681 | 09:43:49 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:43:49 INFO - PROCESS | 1681 | RSS: Main: 197967872 09:43:49 INFO - PROCESS | 1681 | 09:43:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:53 INFO - PROCESS | 1681 | RSS: Main: 197926912 09:43:53 INFO - PROCESS | 1681 | 09:43:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:57 INFO - PROCESS | 1681 | RSS: Main: 198066176 09:43:57 INFO - PROCESS | 1681 | 09:44:00 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:44:00 INFO - PROCESS | 1681 | RSS: Main: 198262784 09:44:00 INFO - PROCESS | 1681 | 09:44:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:03 INFO - PROCESS | 1681 | RSS: Main: 197652480 09:44:03 INFO - PROCESS | 1681 | 09:44:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:05 INFO - PROCESS | 1681 | RSS: Main: 197681152 09:44:05 INFO - PROCESS | 1681 | 09:44:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:08 INFO - PROCESS | 1681 | RSS: Main: 197902336 09:44:08 INFO - PROCESS | 1681 | 09:44:10 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:11 INFO - PROCESS | 1681 | RSS: Main: 198062080 09:44:11 INFO - PROCESS | 1681 | 09:44:14 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:14 INFO - PROCESS | 1681 | RSS: Main: 197951488 09:44:14 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:44:16 INFO - PROCESS | 1681 | RSS: Main: 199671808 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | __start_tp_report 09:44:16 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 09:44:16 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 09:44:16 INFO - PROCESS | 1681 | |i|pagename|runs| 09:44:16 INFO - PROCESS | 1681 | |0;ai-astar;119;112;116;116;115;132;114;116;116;122 09:44:16 INFO - PROCESS | 1681 | |1;audio-beat-detection;152;123;143;160;119;142;160;155;207;119 09:44:16 INFO - PROCESS | 1681 | |2;audio-dft;196;211;226;282;205;279;216;246;272;196 09:44:16 INFO - PROCESS | 1681 | |3;audio-fft;63;98;75;136;72;58;56;75;160;82 09:44:16 INFO - PROCESS | 1681 | |4;audio-oscillator;80;80;120;80;83;90;123;79;82;124 09:44:16 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;90;87;89;102;118;85;95;88;101;87 09:44:16 INFO - PROCESS | 1681 | |6;imaging-darkroom;111;135;140;100;129;102;153;151;99;151 09:44:16 INFO - PROCESS | 1681 | |7;imaging-desaturate;149;143;90;91;92;139;145;93;91;95 09:44:16 INFO - PROCESS | 1681 | |8;json-parse-financial;60;63;62;64;63;65;64;79;60;59 09:44:16 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;60;64;63;70;68;67;65;64;56;79 09:44:16 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;81;82;80;81;83;79;81;83;83;86 09:44:16 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;128;137;146;124;124;127;123;124;143;137 09:44:16 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;166;166;159;163;165;161;165;161;165;163 09:44:16 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;71;64;69;69;73;68;66;69;68;70 09:44:16 INFO - PROCESS | 1681 | __end_tp_report 09:44:16 INFO - PROCESS | 1681 | __start_cc_report 09:44:16 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1040 09:44:16 INFO - PROCESS | 1681 | __end_cc_report 09:44:16 INFO - PROCESS | 1681 | __startTimestamp1466527456707__endTimestamp 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | ------- Summary: start ------- 09:44:16 INFO - PROCESS | 1681 | Number of tests: 14 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:117.80 Median:116.00 stddev:5.67 (4.9%) stddev-sans-first:6.00 09:44:16 INFO - PROCESS | 1681 | Values: 119.0 112.0 116.0 116.0 115.0 132.0 114.0 116.0 116.0 122.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:153.50 stddev:26.25 (17.1%) stddev-sans-first:27.80 09:44:16 INFO - PROCESS | 1681 | Values: 152.0 123.0 143.0 160.0 119.0 142.0 160.0 155.0 207.0 119.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:232.90 Median:236.00 stddev:34.24 (14.5%) stddev-sans-first:33.61 09:44:16 INFO - PROCESS | 1681 | Values: 196.0 211.0 226.0 282.0 205.0 279.0 216.0 246.0 272.0 196.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:87.50 Median:73.50 stddev:34.58 (47.1%) stddev-sans-first:35.53 09:44:16 INFO - PROCESS | 1681 | Values: 63.0 98.0 75.0 136.0 72.0 58.0 56.0 75.0 160.0 82.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:80.00 stddev:19.75 (24.7%) stddev-sans-first:20.28 09:44:16 INFO - PROCESS | 1681 | Values: 80.0 80.0 120.0 80.0 83.0 90.0 123.0 79.0 82.0 124.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:87.50 stddev:10.25 (11.7%) stddev-sans-first:10.76 09:44:16 INFO - PROCESS | 1681 | Values: 90.0 87.0 89.0 102.0 118.0 85.0 95.0 88.0 101.0 87.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:127.10 Median:145.50 stddev:22.26 (15.3%) stddev-sans-first:22.83 09:44:16 INFO - PROCESS | 1681 | Values: 111.0 135.0 140.0 100.0 129.0 102.0 153.0 151.0 99.0 151.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:91.00 stddev:26.99 (29.7%) stddev-sans-first:25.25 09:44:16 INFO - PROCESS | 1681 | Values: 149.0 143.0 90.0 91.0 92.0 139.0 145.0 93.0 91.0 95.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:63.50 stddev:5.67 (8.9%) stddev-sans-first:5.83 09:44:16 INFO - PROCESS | 1681 | Values: 60.0 63.0 62.0 64.0 63.0 65.0 64.0 79.0 60.0 59.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:6.17 (9.3%) stddev-sans-first:6.20 09:44:16 INFO - PROCESS | 1681 | Values: 60.0 64.0 63.0 70.0 68.0 67.0 65.0 64.0 56.0 79.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.50 stddev:1.97 (2.4%) stddev-sans-first:2.06 09:44:16 INFO - PROCESS | 1681 | Values: 81.0 82.0 80.0 81.0 83.0 79.0 81.0 83.0 83.0 86.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:132.50 stddev:8.67 (6.5%) stddev-sans-first:9.11 09:44:16 INFO - PROCESS | 1681 | Values: 128.0 137.0 146.0 124.0 124.0 127.0 123.0 124.0 143.0 137.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:165.00 stddev:2.41 (1.5%) stddev-sans-first:2.37 09:44:16 INFO - PROCESS | 1681 | Values: 166.0 166.0 159.0 163.0 165.0 161.0 165.0 161.0 165.0 163.0 09:44:16 INFO - PROCESS | 1681 | 09:44:16 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:2.50 (3.6%) stddev-sans-first:2.51 09:44:16 INFO - PROCESS | 1681 | Values: 71.0 64.0 69.0 69.0 73.0 68.0 66.0 69.0 68.0 70.0 09:44:16 INFO - PROCESS | 1681 | -------- Summary: end -------- 09:44:16 INFO - PROCESS | 1681 | 09:44:17 INFO - TEST-INFO | 1681: exit 0 09:44:17 INFO - TEST-OK | kraken | took 59196ms 09:44:17 INFO - SUITE-END | took 1168s 09:44:17 INFO - Completed test suite (00:19:29) 09:44:17 INFO - javascript benchmark 09:44:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2988.716071973162, 3570.089627145078, 3616.6925811306037, 3684.5025921500587, 3697.057250401149, 4228.97885510572, 4689.835855745049, 5485.8479719259, 5753.1370294455855, 5774.047282198438, 4721.291806229067, 5733.226014488043, 5789.507891829199, 5800.709964501779, 5811.970940145294, 5207.401148867891, 5243.292155558998, 5246.921296180561, 5307.416184219733, 5325.813596524123, 4480.97759511202, 4596.092621562065, 5084.745762711868, 5199.142141546659, 5320.76056577453, 5279.155335146377, 5309.8938021239555, 5312.814051508198, 5314.432242488475, 5328.493793089669, 5016.247562865563, 5277.947220527784, 5287.4448182941005, 5328.413874473844, 5364.1149210380245, 5008.84973450797, 5107.693538387709, 5173.379194496684, 5215.58275337972, 5227.137522308814, 4671.743054132022, 5224.138017227153, 5575.303087114111, 5627.493525582717, 5637.126245431965, 6165.907511387333, 6317.842053948642, 6322.557420980534, 6326.08271800591, 6350.110984462179, 6171.074338849164, 6181.6600086995195, 6267.2792628847355, 6303.117563541108, 6327.778527751552, 5846.766129354821, 5871.089981052944, 5890.14592884038, 5895.911561326584, 5907.734151963172, 6220.471259942956, 6266.843328916722, 6295.213098362705, 6305.274893387227, 6316.905246421307, 6233.501319894398, 6238.812835614939, 6242.282137554149, 6264.436200741955, 6266.216722909637, 6313.0, 6315.463185629181, 6327.209098862642, 6334.461570766536, 6335.936640633535, 6152.507799376039, 6241.438270555672, 6289.559730818799, 6297.307296197415, 6314.305426403092, 5665.49010589049, 5892.9116063258225, 6305.905411418832, 6318.968405157945, 6335.841603959846, 4993.450720420751, 5036.104240061979, 5045.192769156991, 5047.268146118792, 5058.013687330935, 4472.195004899084, 4493.348464472699, 4547.136044151601, 4566.840160594364, 4613.123506533748, 5445.883593863248, 5676.602637815313, 5885.5585831062845, 6050.546209034252, 6123.252118380239, 6264.310925798159, 6282.842928926813, 6283.842903927439, 6294.685265736694, 6298.118263443122, 5958.612690175125, 6064.636121832704, 6088.786892458743, 6128.044828652092, 6152.169457123231, 5519.227308176858, 5538.833834984957, 5541.833744987657, 5557.0, 5569.498745112959, 6610.438112760373, 6618.8345291368105, 6625.536212465177, 6631.767888123893, 6641.302663220292, 6189.288231853395, 6210.223722034746, 6281.96859015702, 6298.370162983665, 6326.240851097898, 6559.0, 6606.207255129415, 6609.272979972103, 6634.104395906491, 6635.838728222445, 5021.970496048374, 6133.2428548619655, 6222.906656400248, 6300.9684951574945, 6301.6219026858535, 6215.347388524321, 6231.22109736283, 6272.059191121186, 6319.557630965972, 6323.905141422974, 6026.457618814327, 6216.751329946752, 6225.62646241227, 6238.500919926304, 6249.531285153541, 6065.969670151621, 6083.117947897618, 6186.288576813544, 6221.626702397871, 6225.408586184305, 5980.730867110903, 6035.818925432244, 6037.275526936795, 6044.576879618385, 6057.757689692539, 6100.328963813889, 6151.200343955436, 6183.072539119168, 6183.721732522137, 6185.443310102112, 5949.583529152917, 6015.909761353496, 6034.7284372202475, 6048.637081774931, 6063.454289114001], "value": 5739.72137853509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.3934634190194, 545.487241992527, 579.733322671571, 581.444720292121, 588.4078161088097, 38755.806220968894, 39752.80123599385, 39830.601693983015, 39848.402273965876, 39911.60309389172, 43192.704218873485, 43843.90390240245, 43938.34092488615, 44026.11947761043, 44033.55966440334, 9624.518774061322, 9730.65942692006, 9743.123118919295, 9765.511724413787, 9889.456079915602, 9712.320137590372, 9792.971737967518, 9817.901820981806, 9825.164860986824, 9838.803223935553, 9795.510224488748, 9871.555779989918, 9874.407535547854, 9879.703608891745, 9886.307958442912, 9743.123118919331, 9754.756131096743, 9823.95088024559, 9839.917609062997, 9856.90143098567, 9713.174380177623, 9794.706158815246, 9817.460039697813, 9817.460039697813, 9837.114659680663, 33389.0, 39429.01427464308, 39456.60543394558, 39915.60084399148, 40183.7990810047, 3190.1865024418858, 3347.581552305962, 3350.463925771889, 3880.282147802637, 3988.900277493086, 26380.340491487867, 26556.60165097525, 27390.0, 27750.861245693643, 28146.577801333, 2772.487089888377, 2779.43021680555, 2783.6102945587636, 2797.2727090956296, 2998.5502174673975, 729524.0, 751589.0, 754051.0, 755206.2239688821, 758454.0, 53868.0, 63957.04064439037, 64153.0, 64702.352976470575, 64791.02813457802, 71024.2897571028, 71700.64149679219, 72399.0, 72483.91274130892, 72787.63606181936, 18760.71858922117, 18898.433047008613, 18905.527361815788, 18920.905395472935, 18958.0, 14283.78574321386, 14307.856921430652, 14782.48261310849, 14787.92606036963, 14853.777193342108, 16951.576210594834, 18521.629567408578, 20346.796532034787, 20522.281720139723, 20593.48516287105, 7717.305442510089, 8783.472991620523, 8827.382083254111, 8911.866322005175, 8990.168097049409, 932.1563984593953, 974.8147851908114, 1058.9576416943237, 1105.132471010253, 1204.9031898593146, 29271.560926586117, 30175.0, 30532.68861892608, 30649.693503064686, 31190.532142017888, 1078.336822853939, 1125.471028616549, 1214.2957084890743, 1238.4427007846505, 1243.800991841319, 13960.86039139616, 15501.922490387477, 15778.526644200692, 15795.499427554363, 16977.89643673157, 649588.0, 806214.9689251634, 808117.0, 815005.0, 818616.0, 59792.40207597955, 59950.0, 60546.0, 60549.0, 60722.089168662504, 1576.9526914192593, 1583.9841601583837, 1587.6904003719164, 1589.8569128778233, 1592.5302035899206, 28743.0, 30780.23049423782, 31079.378412432077, 31115.84442077775, 31277.218069547533, 52079.95840083101, 52431.73784131055, 53147.46852531425, 53766.0, 53849.0, 598415.0, 599056.0, 605932.0, 606730.0, 607779.0, 27042.188734338, 28055.579166312113, 28292.717072829008, 28455.0, 28527.572086418306, 45613.77193114013, 45677.314840276755, 46190.0, 46821.53178468172, 47341.28988065113, 37978.24043519169, 38028.42957355697, 38263.0, 38330.23339533249, 38513.80743096267, 37630.059248519006, 37655.81172094122, 38077.809610951765, 38171.42742858804, 38328.80835595804, 36502.72240471571, 37173.0, 37872.0, 38441.231175376895, 38598.4210236841, 31699.35457422426, 32378.514322284715, 32782.836085820374, 33013.00960971175, 33192.502112467846, 21756.023302207494, 24911.0, 25515.36211594725, 26502.939882404487, 26991.0], "value": 20954.017019077524, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.72826293331735, 413.89238797912543, 414.69934297634217, 417.8892593462731, 425.6573458366016, 18253.817461825376, 20851.687224691625, 21439.820809855453, 21607.0, 21624.89187554062, 20722.585548289062, 21236.57526849462, 21326.46683832905, 21341.0, 21361.465963350925, 5686.175504551838, 5942.316633587139, 5972.0444728843395, 5994.43052909975, 5996.520278377741, 5905.31627463445, 5928.081147422135, 5960.672163031031, 6642.701078451457, 6852.6231057291825, 6776.288489708559, 6781.457483401341, 6802.591844489347, 6803.557768745016, 6821.693023813916, 5987.401259873999, 6152.323244443108, 6190.102435146923, 6246.937530624704, 6752.426043786284, 5858.326292476335, 5941.732622032019, 5943.64338139713, 5977.98374276374, 5984.700764961778, 25329.860156292827, 25406.36484087894, 25884.611730824054, 26255.474890502275, 26313.342166445993, 2204.647256438962, 2211.3365990202874, 2315.2591170825344, 2354.8940297686645, 2665.2803742989477, 8415.410921235516, 9190.494522801244, 9771.804563908752, 10327.741806454824, 11205.21563490556, 1712.8030276518236, 1812.9909350453295, 1843.023197705205, 1864.3567969050791, 1896.4121122452084, 92185.0, 110518.0, 111388.0, 117044.99999999914, 117404.0, 11766.647000589996, 11973.820392693942, 11976.28142311464, 12014.0, 12069.27584344942, 12865.228086314852, 13950.0, 15001.849981500263, 15360.617544420833, 15509.457168999032, 8405.949256342958, 9160.587773550073, 9232.261419086457, 9398.859017114748, 9407.341486095907, 9274.21169200612, 9499.525023748785, 9521.523923803783, 9528.475933823705, 9569.904300957041, 4705.60002399793, 4762.738049407316, 4791.425028996473, 4811.855644330676, 4851.80592776285, 2009.5277609761695, 2100.7479102507796, 2134.4983928776724, 2139.6041732279577, 2142.421546182522, 924.9445033298024, 975.6438899801522, 985.6599473181741, 991.6330957545754, 1021.4126877045728, 20039.198432062847, 20309.289174879104, 20413.69379459309, 20574.58850822975, 20657.0, 836.3560058754725, 977.8533220016913, 1008.1430783833829, 1017.4505766885799, 1030.6083688198437, 10751.0, 10957.83563246552, 10982.176336774774, 10998.780024399468, 11009.009189172853, 628485.0, 638581.0, 640344.0, 645638.0, 669717.0, 120250.3987480057, 121081.0, 124319.75680243263, 128329.0, 129205.35397322953, 1029.6602121299952, 1034.2811745836723, 1035.4667346316653, 1039.7452624107143, 1041.1098510773331, 17833.375831845824, 17954.371596994042, 18134.183961722018, 18224.72662910031, 18282.634347313244, 40536.0, 48980.75509622429, 49865.75335616639, 52484.687882803235, 55668.608284793205, 70842.64578677074, 71814.92277615944, 72340.0, 72849.0, 72851.0, 8035.477693949874, 8091.393145514227, 8260.091389946982, 8414.87377689323, 11491.965723084628, 12917.09580329368, 13187.076904616584, 13471.989600779785, 13486.12340197884, 13856.0, 9515.238780897651, 10669.46652667379, 11162.665120046411, 11862.0, 12138.817917731418, 5358.678479291255, 8568.914310856811, 11469.254498457574, 11496.103142981314, 11641.6507504775, 11952.462139203582, 11990.640280791591, 12061.51753929833, 12116.21244619097, 12165.939170304091, 11616.593419230287, 12102.0, 12620.684482888002, 12647.430865611253, 12753.553625623059, 11751.118666099906, 11892.583759568373, 11964.58123965657, 11992.160548761503, 12017.939910300393], "value": 9936.577796551595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.8947273690842, 1571.3400371843825, 1890.725844752511, 1996.4908948218201, 2043.7343145391096, 6550.836229094275, 8186.386021048415, 8760.737177884657, 8972.0, 9493.386124358858, 8850.33622478315, 8885.689000884955, 8915.375923685344, 8957.865632015524, 8981.101889811016, 2512.610545365467, 2513.987430062847, 2522.545941730488, 2531.354504601324, 2532.658091157698, 2267.342470683508, 2482.9751702483013, 2539.21284401836, 2540.7586279303528, 2558.48830233953, 2496.750324967498, 2504.7745702886737, 2525.936851578707, 2551.5152121097026, 2563.1797824696105, 2204.1954686539457, 2514.421683012899, 2542.377117606198, 2544.643749875019, 2563.0772921748157, 2509.0591028364365, 2524.7222805491383, 2537.0612873236837, 2553.885075171622, 2576.2271318604344, 7922.04935407749, 9000.77498062547, 9005.279577633839, 9010.774730631721, 9018.774530636721, 5580.972095139539, 5597.3003374578175, 5607.130894711286, 5631.211630371751, 5668.943310566883, 3639.253952939641, 3764.134249122717, 3767.4913886625227, 3787.110029143149, 3808.352580061396, 1077.5151181968067, 1081.1080858291941, 1101.3502033800016, 1106.2753896198003, 1110.900018998286, 12252.816207756801, 12516.937415312865, 12532.0, 12579.433925473375, 12584.24494530322, 4999.650024498323, 5039.294498770175, 5051.747412629354, 5114.0, 5127.4872512748425, 10364.429956352473, 10575.524101415454, 10613.9469302653, 10649.520771565298, 10653.467326633638, 4806.567408933212, 5566.192902029183, 5608.130739735266, 5613.943860561342, 5620.353659329147, 4628.722276663411, 5076.6192535559985, 5103.055934652101, 5134.409542902539, 5194.22086687, 4206.978965105216, 4224.471941007374, 4247.384129301234, 4269.487661480643, 4271.572842715766, 1401.8878489721, 1425.4939496478808, 1427.093795439903, 1428.4928850258216, 1443.81952255968, 926.935114541989, 944.7968686732386, 951.7144856543009, 960.1406740966795, 992.5831150916632, 6043.969780151071, 6057.091436284592, 6158.107074474266, 6197.4732147767945, 6234.376562343729, 485.43204450792655, 488.6896820518925, 492.3918960084293, 1044.7074819050679, 1046.5552140340326, 4041.777702226347, 4098.0, 4117.732347397409, 4133.318002529549, 4168.874933751992, 10479.056884880398, 10774.030337269684, 10826.404547749791, 10896.291741036806, 10965.780684386267, 10464.843027354596, 10614.787704246026, 10682.0, 10723.94638026805, 10741.462926853626, 7044.401225895754, 8348.373871959628, 8486.915130848736, 8526.78683032929, 8582.270507006975, 5760.279965004374, 6041.909371359521, 6058.969705151446, 6070.7875224366935, 6084.026555751058, 9629.662961796303, 9803.16673082796, 9857.704268871945, 9858.162056225014, 9917.15704165154, 9926.503674816086, 10143.34068285559, 10145.64490242838, 10395.220358473296, 10440.008199221062, 6998.230194678479, 7436.0, 7711.305982461606, 7793.415493838098, 7945.0, 8436.493810371398, 8477.0, 8524.27543658796, 8541.91458085436, 8559.058503564478, 7490.475666703496, 7625.618719064136, 7661.233876612294, 7722.845543088994, 7733.961330193313, 6250.718717657625, 7485.513441626277, 7547.433942454228, 7562.395008399425, 7657.851322301699, 7230.349268565854, 7240.818979525554, 7282.0533330668795, 7300.96349518249, 7303.561786292839, 4965.503449655005, 4973.45292017885, 5036.8992620146655, 5047.6214283929585, 5168.328117344721, 4593.28804035375, 4783.545563171493, 5019.774110164978, 5187.714675692873, 5214.426413094632], "value": 4726.3453434590765, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.5351694936411, 341.375283231686, 341.6702881719142, 347.5117459968744, 352.922357081442, 6718.496112791542, 6739.9663001684985, 6834.760783372583, 6838.213605435377, 6881.690323935422, 6733.293004234558, 6739.6411256060355, 6744.2244141923575, 6753.358430949064, 6759.053732477445, 4792.904141917168, 4890.6576539642065, 5024.120778863702, 5031.622628302874, 5034.219695947116, 4956.40523137224, 4978.850634480971, 5004.099262132815, 5015.297858299841, 5027.8491645250515, 4941.555260026596, 4958.305837182797, 4965.354503914504, 5000.874978125539, 5030.547250747432, 4688.929666055012, 5028.547430731215, 5035.597152227831, 5039.899202015976, 5043.949560504385, 4334.978325108385, 4336.8265269389185, 4343.543927887558, 4344.847930322424, 4358.607725304706, 6203.47270482005, 6468.062130991027, 6500.544961852672, 6506.739730410778, 6516.739330426777, 5807.332156801979, 5828.2806572061345, 5849.064149736065, 5854.443827836349, 5883.441073098049, 6359.109724638554, 6675.065490831287, 7233.45749068822, 7238.167610724729, 7264.309890560388, 4706.1175529788015, 4708.693934894221, 4711.693739906895, 4714.057188562267, 4736.739479328636, 7322.231165727629, 7489.475736698433, 7578.886316705199, 7584.582847943361, 7605.885911711329, 4761.999980004238, 4774.21225497789, 4782.545658162469, 4791.976040119846, 4796.5203479652455, 4810.542998415145, 4842.322074909515, 4876.5854902332985, 4876.829310974099, 4890.266460031024, 5795.043817770105, 5801.564882633735, 5802.47777700009, 5823.56323275756, 5824.15549745293, 7251.166115896739, 7258.564486130849, 7260.2013778484325, 7263.963680181671, 7268.87332463469, 1660.5350501859498, 1680.5378520906604, 1681.201429321087, 1681.7645529625863, 1682.9061110278417, 1037.7509397744486, 1069.2301542889263, 1075.1184029096066, 1076.8976947190004, 1082.826747720361, 5593.804216852391, 6958.234594194634, 6978.895316570255, 6992.860142797115, 7017.614031228331, 7305.707771689179, 7385.3722433592675, 7419.851602968019, 7486.663100160506, 7487.1764105948305, 7080.964595176991, 7093.538919970186, 7101.680424380916, 7111.288871112847, 7134.643267836691, 3258.625258095349, 3964.920701585952, 4005.399190121505, 4011.5587285398583, 4019.4171845082883, 6872.62200578963, 8144.104148543655, 8156.551389673625, 8159.428839981263, 8235.752927412188, 7595.658195381221, 7751.1861254568585, 7761.301482866569, 7832.177621349791, 7876.72431464896, 8099.230573095547, 8138.877916831252, 8140.226678465537, 8142.470739402039, 8173.959130204311, 7536.962315188389, 7557.924420755722, 7558.470907036674, 7564.735234266775, 7574.545527268381, 6626.503012274003, 6655.434288085373, 7477.065366829146, 7479.850402991801, 7484.663190156347, 6220.440160385406, 8122.309603683517, 8128.390370722102, 8139.75580732579, 8151.184881511801, 4137.731047481904, 4767.904641907073, 4794.808207671654, 4801.423829140525, 4807.6394270429155, 7532.585707786125, 7533.623318834146, 7544.358729508053, 7553.395728341609, 7581.583012934121, 6487.54587178893, 6617.602943823386, 7204.639768011473, 7235.095613048369, 7271.163816160997, 6969.233384327618, 7268.563886166847, 7280.599567023864, 7281.599512026677, 7290.198078211286, 5634.295713035871, 7086.291370863079, 7105.46708996817, 7121.893171602534, 7128.037714908628, 7252.166000909963, 7267.236940121423, 7273.418126549969, 7280.0535930328715, 7285.490015698851, 6547.690461907733, 6891.586504809728, 7116.822079448055, 7117.644117794193, 7121.14546254432], "value": 5301.252246405145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.2131548718972, 699.8075529229461, 703.9651711983382, 710.7015053677455, 784.6547519091599, 15293.0, 15462.07227566344, 15478.38086476541, 15601.375944962188, 15643.608909777233, 14357.138571685706, 14395.712085758278, 14409.279536023208, 14465.05977111487, 16010.919945400257, 960.3661583354988, 961.341481085457, 981.8429051351785, 982.8427451607744, 983.0955520920734, 795.6181033104114, 970.7621632699999, 971.9076687714655, 976.4190306767462, 976.9609215631366, 1000.5147503460815, 1014.822406078937, 1015.1979935850669, 1022.8874823769416, 1023.4422239879284, 966.4104896013426, 966.9613215471372, 1020.4693559349134, 1024.636254129788, 1027.2193133218732, 722.3390597603187, 764.8470305938835, 770.6108415250319, 771.3597713897452, 772.9884051739228, 15411.22943852803, 15640.139792311418, 15721.685566288725, 15736.0, 15737.134457604827, 12544.937275313656, 12573.308468034254, 12736.36318184087, 12746.617601471971, 12865.807012894815, 10252.475247524753, 11235.269707468988, 11473.713157171054, 11558.884411155865, 11599.420028998516, 3558.893233202982, 3578.087587665155, 3599.4600809878466, 3666.046827824758, 3705.7035437164964, 84290.57854710687, 84996.0, 85297.57351213266, 85758.0, 86525.0, 8310.335173186131, 8529.74410767678, 9333.533323333806, 9586.760330991781, 9670.806583868283, 21027.8948605256, 21551.35345939593, 21651.78348216529, 21745.891270543547, 21777.455563611038, 7429.0, 7594.468387212842, 7654.043244594426, 7733.574653394005, 7767.650455729506, 4086.3461846105074, 4167.729097608646, 4212.789360531961, 4283.507396649363, 4340.414044104069, 3750.3061933541835, 3751.118487155516, 3756.417755247941, 3823.1206822430995, 3849.8652547160714, 682.0383259603934, 683.5830143612393, 687.2508965227926, 688.277308825731, 688.3151264491862, 503.64996327552734, 537.8865747901806, 594.7650677982173, 625.3308959413384, 650.6226388694546, 7940.483868548641, 8518.190771876662, 8569.014563325298, 8582.613782379807, 8717.782055448537, 521.3743507790666, 529.6319058254476, 552.7706002009121, 573.8364566098726, 625.8873402787451, 3195.3130077033547, 3816.4847745553993, 3908.863189788344, 3985.163115745726, 3989.5412027616617, 86775.56612216898, 86880.0, 87486.0, 97307.51346243224, 98536.0, 76128.0, 76466.0, 76698.61650691711, 77404.0, 78190.60904695553, 71916.92124618134, 73124.2687573128, 73303.26696732965, 73557.26442735612, 74053.88919166217, 6998.6150761708595, 8582.541225964043, 8595.355348349023, 8650.394472386872, 8654.177853103945, 47095.529044710485, 47472.81317967078, 47484.0, 48131.03737925292, 48863.755681221366, 51988.0, 52260.477395225564, 64354.678226608565, 64500.677496614095, 64588.67705661442, 11605.88394116048, 11610.94194529022, 11787.469563869818, 11976.221545599508, 12081.395930203631, 11731.0, 12105.0, 12201.08491863096, 12304.753904921672, 12541.184822986477, 11037.448127593749, 11515.53937842477, 11639.349150655777, 12046.337451439904, 12122.33327166979, 11485.0, 11583.131265154978, 11756.0, 11846.644600661994, 12099.81850272229, 11181.832272516082, 11315.377654229096, 11369.658910232707, 11553.422328883691, 11723.296602203895, 9866.210703143588, 9950.502474876372, 9969.202463803023, 9995.950020249853, 10100.393976361147, 9633.277504187074, 9648.903510965081, 9693.515324233902, 9761.755956101155, 9824.0], "value": 7062.7012492944605, "unit": "score"}], "name": "dromaeo_css", "value": 7718.825987539008, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 112.0, 116.0, 116.0, 115.0, 132.0, 114.0, 116.0, 116.0, 122.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 143.0, 160.0, 119.0, 142.0, 160.0, 155.0, 207.0, 119.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 211.0, 226.0, 282.0, 205.0, 279.0, 216.0, 246.0, 272.0, 196.0], "value": 232.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 98.0, 75.0, 136.0, 72.0, 58.0, 56.0, 75.0, 160.0, 82.0], "value": 87.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 120.0, 80.0, 83.0, 90.0, 123.0, 79.0, 82.0, 124.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 102.0, 118.0, 85.0, 95.0, 88.0, 101.0, 87.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 135.0, 140.0, 100.0, 129.0, 102.0, 153.0, 151.0, 99.0, 151.0], "value": 127.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 143.0, 90.0, 91.0, 92.0, 139.0, 145.0, 93.0, 91.0, 95.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 62.0, 64.0, 63.0, 65.0, 64.0, 79.0, 60.0, 59.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 63.0, 70.0, 68.0, 67.0, 65.0, 64.0, 56.0, 79.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 81.0, 83.0, 79.0, 81.0, 83.0, 83.0, 86.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 137.0, 146.0, 124.0, 124.0, 127.0, 123.0, 124.0, 143.0, 137.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 159.0, 163.0, 165.0, 161.0, 165.0, 161.0, 165.0, 163.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 69.0, 69.0, 73.0, 68.0, 66.0, 69.0, 68.0, 70.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1589.2000000000003, "alertThreshold": 2.0}]} 09:44:17 INFO - Return code: 0 09:44:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:44:18 ERROR - # TBPL SUCCESS # 09:44:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:18 INFO - 2016-06-21 09:44:18.045 screenresolution[1686:10493] starting screenresolution argv=screenresolution get 09:44:18 INFO - 2016-06-21 09:44:18.048 screenresolution[1686:10493] Display 0: 1600x1200x32@60 09:44:18 INFO - 2016-06-21 09:44:18.054 screenresolution[1687:10496] starting screenresolution argv=screenresolution list 09:44:18 INFO - Available Modes on Display 0 09:44:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:18 INFO - Intel Iris: 09:44:18 INFO - Chipset Model: Intel Iris 09:44:18 INFO - Type: GPU 09:44:18 INFO - Bus: Built-In 09:44:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:18 INFO - Vendor: Intel (0x8086) 09:44:18 INFO - Device ID: 0x0a2e 09:44:18 INFO - Revision ID: 0x0009 09:44:18 INFO - Displays: 09:44:18 INFO - Display: 09:44:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:18 INFO - Display Serial Number: 1600x1200 60 09:44:18 INFO - Main Display: Yes 09:44:18 INFO - Mirror: Off 09:44:18 INFO - Online: Yes 09:44:18 INFO - Rotation: Supported 09:44:18 INFO - Return code: 0 09:44:18 INFO - Running post-action listener: _resource_record_post_action 09:44:18 INFO - Running post-run listener: _resource_record_post_run 09:44:19 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 31587840; Write bytes: 497570816; Read time: 195; Write time: 1576 09:44:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 182968832; Write bytes: 176052224; Read time: 16601; Write time: 380 09:44:19 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 30666240; Write bytes: 301136896; Read time: 184; Write time: 1137 09:44:19 INFO - Running post-run listener: _upload_blobber_files 09:44:19 INFO - Blob upload gear active. 09:44:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:44:19 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:44:19 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:44:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:44:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:20 INFO - (blobuploader) - INFO - Done attempting. 09:44:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:44:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:44:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:21 INFO - (blobuploader) - INFO - Done attempting. 09:44:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:21 INFO - Return code: 0 09:44:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cd73c17f4acef984c113888098964eac8dc5efcb700363f7520a78e115b20c834817d26873c113fba28b84f4887b5ca8af1ef2d1443461c01ae35775bcf0ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/123b249b1e6543585afdd6247cc84e7ee3bd1548510609b200a910795d7b469e99fa65996ae721262b3e6fd3f4e33d7e33dc723dcfc732b1aa129e8ef69b791c"} 09:44:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:21 INFO - Contents: 09:44:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cd73c17f4acef984c113888098964eac8dc5efcb700363f7520a78e115b20c834817d26873c113fba28b84f4887b5ca8af1ef2d1443461c01ae35775bcf0ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/123b249b1e6543585afdd6247cc84e7ee3bd1548510609b200a910795d7b469e99fa65996ae721262b3e6fd3f4e33d7e33dc723dcfc732b1aa129e8ef69b791c"} 09:44:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:21 INFO - Copying logs to upload dir... 09:44:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.838854 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-21 09:44:21.809703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 09:44:21.813158) ========= 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.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cd73c17f4acef984c113888098964eac8dc5efcb700363f7520a78e115b20c834817d26873c113fba28b84f4887b5ca8af1ef2d1443461c01ae35775bcf0ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/123b249b1e6543585afdd6247cc84e7ee3bd1548510609b200a910795d7b469e99fa65996ae721262b3e6fd3f4e33d7e33dc723dcfc732b1aa129e8ef69b791c"} build_url:https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/Z7VCUB6MQIGTrVdicXIp-g/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cd73c17f4acef984c113888098964eac8dc5efcb700363f7520a78e115b20c834817d26873c113fba28b84f4887b5ca8af1ef2d1443461c01ae35775bcf0ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/123b249b1e6543585afdd6247cc84e7ee3bd1548510609b200a910795d7b469e99fa65996ae721262b3e6fd3f4e33d7e33dc723dcfc732b1aa129e8ef69b791c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 09:44:21.847633) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:44:21.847982) ========= 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.bPVeLawLed/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.laLCoKwyqF/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005109 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:44:21.899063) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 09:44:21.899781) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 09:44:21.900450) ========= ========= Total master_lag: 0.22 =========