builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0339 starttime: 1467656230.61 results: success (0) buildid: 20160704090224 builduid: 2ff758dbc3b4485a83b3975cf8b87610 revision: f378a56b25ce2a2997b263c1857629f3f18d7400 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:10.614655) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:10.615125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:10.615440) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:10.639958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:10.640338) ========= 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.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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-07-04 11:17:10-- 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% 18.5M=0.001s 2016-07-04 11:17:10 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111379 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:11.008350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:11.008667) ========= 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.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.027146 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:11.056117) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 11:17:11.056424) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f378a56b25ce2a2997b263c1857629f3f18d7400 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f378a56b25ce2a2997b263c1857629f3f18d7400 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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-07-04 11:17:11,138 truncating revision to first 12 chars 2016-07-04 11:17:11,139 Setting DEBUG logging. 2016-07-04 11:17:11,139 attempt 1/10 2016-07-04 11:17:11,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f378a56b25ce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 11:17:11,876 unpacking tar archive at: mozilla-central-f378a56b25ce/testing/mozharness/ program finished with exit code 0 elapsedTime=0.996668 ========= master_lag: 0.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 11:17:12.615695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:12.616009) ========= script_repo_revision: f378a56b25ce2a2997b263c1857629f3f18d7400 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:12.616413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:12.616673) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 11:17:12.635447) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-04 11:17:12.635837) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:12 INFO - MultiFileLogger online at 20160704 11:17:12 in /builds/slave/test 11:17:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:17:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:17:12 INFO - {'append_to_log': False, 11:17:12 INFO - 'base_work_dir': '/builds/slave/test', 11:17:12 INFO - 'blob_upload_branch': 'Firefox', 11:17:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:17:12 INFO - 'branch': 'Firefox', 11:17:12 INFO - 'buildbot_json_path': 'buildprops.json', 11:17:12 INFO - 'config_files': ('talos/mac_config.py',), 11:17:12 INFO - 'default_actions': ('clobber', 11:17:12 INFO - 'read-buildbot-config', 11:17:12 INFO - 'download-and-extract', 11:17:12 INFO - 'populate-webroot', 11:17:12 INFO - 'create-virtualenv', 11:17:12 INFO - 'install', 11:17:12 INFO - 'run-tests'), 11:17:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:17:12 INFO - 'download_minidump_stackwalk': True, 11:17:12 INFO - 'download_symbols': 'ondemand', 11:17:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:17:12 INFO - 'tooltool.py': '/tools/tooltool.py', 11:17:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:17:12 INFO - '/tools/misc-python/virtualenv.py')}, 11:17:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:17:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:17:12 INFO - 'installer_path': 'installer.exe', 11:17:12 INFO - 'log_level': 'info', 11:17:12 INFO - 'log_name': 'talos', 11:17:12 INFO - 'log_to_console': True, 11:17:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:17:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:17:12 INFO - 'opt_config_files': (), 11:17:12 INFO - 'pip_index': False, 11:17:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:12 INFO - 'cmd': ('bash', 11:17:12 INFO - '-c', 11:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:12 INFO - 'enabled': True, 11:17:12 INFO - 'halt_on_failure': False, 11:17:12 INFO - 'name': 'check_screen_resolution'},), 11:17:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:12 INFO - 'cmd': ('bash', 11:17:12 INFO - '-c', 11:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:12 INFO - 'enabled': True, 11:17:12 INFO - 'halt_on_failure': False, 11:17:12 INFO - 'name': 'check_screen_resolution'},), 11:17:12 INFO - 'run_cmd_checks_enabled': True, 11:17:12 INFO - 'sps_profile': False, 11:17:12 INFO - 'sps_profile_interval': 0, 11:17:12 INFO - 'suite': 'dromaeojs', 11:17:12 INFO - 'system_bits': '32', 11:17:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:17:12 INFO - 'title': 't-yosemite-r7-0339', 11:17:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:17:12 INFO - 'use_talos_json': True, 11:17:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:17:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:17:12 INFO - 'work_dir': 'build'} 11:17:12 INFO - ##### 11:17:12 INFO - ##### Running clobber step. 11:17:12 INFO - ##### 11:17:12 INFO - Running pre-action listener: _resource_record_pre_action 11:17:12 INFO - Running main action method: clobber 11:17:12 INFO - rmtree: /builds/slave/test/build 11:17:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:17:14 INFO - Running post-action listener: _resource_record_post_action 11:17:14 INFO - ##### 11:17:14 INFO - ##### Finished clobber step (success) 11:17:14 INFO - ##### 11:17:14 INFO - ##### 11:17:14 INFO - ##### Running read-buildbot-config step. 11:17:14 INFO - ##### 11:17:14 INFO - Running pre-action listener: _resource_record_pre_action 11:17:14 INFO - Running main action method: read_buildbot_config 11:17:14 INFO - Using buildbot properties: 11:17:14 INFO - { 11:17:14 INFO - "project": "", 11:17:14 INFO - "product": "firefox", 11:17:14 INFO - "script_repo_revision": "production", 11:17:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:17:14 INFO - "repository": "", 11:17:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:17:14 INFO - "buildid": "20160704090224", 11:17:14 INFO - "pgo_build": "False", 11:17:14 INFO - "basedir": "/builds/slave/test", 11:17:14 INFO - "buildnumber": 274, 11:17:14 INFO - "slavename": "t-yosemite-r7-0339", 11:17:14 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:17:14 INFO - "platform": "yosemite_r7", 11:17:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:17:14 INFO - "branch": "mozilla-central", 11:17:14 INFO - "repo_path": "mozilla-central", 11:17:14 INFO - "revision": "f378a56b25ce2a2997b263c1857629f3f18d7400", 11:17:14 INFO - "stage_platform": "macosx64", 11:17:14 INFO - "builduid": "2ff758dbc3b4485a83b3975cf8b87610", 11:17:14 INFO - "slavebuilddir": "test" 11:17:14 INFO - } 11:17:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:17:14 INFO - Running post-action listener: _resource_record_post_action 11:17:14 INFO - ##### 11:17:14 INFO - ##### Finished read-buildbot-config step (success) 11:17:14 INFO - ##### 11:17:14 INFO - ##### 11:17:14 INFO - ##### Running download-and-extract step. 11:17:14 INFO - ##### 11:17:14 INFO - Running pre-action listener: _resource_record_pre_action 11:17:14 INFO - Running main action method: download_and_extract 11:17:14 INFO - mkdir: /builds/slave/test/build/tests 11:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:14 INFO - https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:17:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:17:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:17:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:17:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 11:17:16 INFO - Downloaded 1199 bytes. 11:17:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:17:16 INFO - Using the following test package requirements: 11:17:16 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:17:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:17:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:17:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'jsshell-mac.zip'], 11:17:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:17:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:17:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:17:16 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:17:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:17:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:17:16 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:17:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:17: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')]} 11:17:16 INFO - https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:17:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:17:18 INFO - Downloaded 18795233 bytes. 11:17:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:17:21 INFO - Return code: 0 11:17:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:21 INFO - https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:17:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:17:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:17:22 INFO - Downloaded 18795233 bytes. 11:17:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:17:25 INFO - Return code: 0 11:17: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')]} 11:17:25 INFO - https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:17:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:17:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:17:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:17:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:17:26 INFO - Downloaded 14403333 bytes. 11:17:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:17:27 INFO - Return code: 0 11:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:27 INFO - https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 11:17:30 INFO - Downloaded 112415735 bytes. 11:17:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:17:30 INFO - mkdir: /builds/slave/test/properties 11:17:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:17:30 INFO - Writing to file /builds/slave/test/properties/build_url 11:17:30 INFO - Contents: 11:17:30 INFO - build_url:https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:17:30 INFO - Running post-action listener: _resource_record_post_action 11:17:30 INFO - Running post-action listener: set_extra_try_arguments 11:17:30 INFO - ##### 11:17:30 INFO - ##### Finished download-and-extract step (success) 11:17:30 INFO - ##### 11:17:30 INFO - ##### 11:17:30 INFO - ##### Running populate-webroot step. 11:17:30 INFO - ##### 11:17:30 INFO - Running pre-action listener: _resource_record_pre_action 11:17:30 INFO - Running main action method: populate_webroot 11:17:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:17:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:17:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:17:30 INFO - '--tppagecycles', 11:17:30 INFO - '7'], 11:17:30 INFO - 'tests': ['tsvgm']}}, 11:17:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:17:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:17:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:17:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:17:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['damp', 'tps']}, 11:17:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'tests': ['damp', 'tps']}, 11:17:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['dromaeo_dom']}, 11:17:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:17:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['basic_compositor_video']}, 11:17:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:17:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'other-e10s': {'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['a11yr', 11:17:30 INFO - 'ts_paint', 11:17:30 INFO - 'tpaint', 11:17:30 INFO - 'sessionrestore', 11:17:30 INFO - 'sessionrestore_no_auto_restore', 11:17:30 INFO - 'tabpaint']}, 11:17:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['tsvgx', 11:17:30 INFO - 'tsvgr_opacity', 11:17:30 INFO - 'tart', 11:17:30 INFO - 'tscrollx', 11:17:30 INFO - 'cart']}, 11:17:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:17:30 INFO - 'tsvgr_opacity', 11:17:30 INFO - 'tart', 11:17:30 INFO - 'tscrollx', 11:17:30 INFO - 'cart']}, 11:17:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'talos_options': ['--disable-e10s'], 11:17:30 INFO - 'tests': ['tp5o']}, 11:17:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'tests': ['tp5o']}, 11:17:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'talos_options': ['--disable-e10s', 11:17:30 INFO - '--xperf_path', 11:17:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:30 INFO - 'tests': ['tp5n']}, 11:17:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:30 INFO - 'talos_options': ['--xperf_path', 11:17:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:30 INFO - 'tests': ['tp5n']}}, 11:17:30 INFO - 'talos.zip': {'path': '', 11:17:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:17:30 INFO - Running post-action listener: _resource_record_post_action 11:17:30 INFO - ##### 11:17:30 INFO - ##### Finished populate-webroot step (success) 11:17:30 INFO - ##### 11:17:30 INFO - ##### 11:17:30 INFO - ##### Running create-virtualenv step. 11:17:30 INFO - ##### 11:17:30 INFO - Running pre-action listener: _resource_record_pre_action 11:17:30 INFO - Running main action method: create_virtualenv 11:17:30 INFO - Creating virtualenv /builds/slave/test/build/venv 11:17:30 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 11:17:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:17:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:17:31 INFO - Using real prefix '/tools/python27' 11:17:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:17:31 INFO - Installing distribute.............................................................................................................................................................................................done. 11:17:34 INFO - Installing pip.................done. 11:17:34 INFO - Return code: 0 11:17:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:17:34 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')]} 11:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:34 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')]} 11:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:34 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:34 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 11:17:34 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 11:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:34 INFO - 'HOME': '/Users/cltbld', 11:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:34 INFO - 'LOGNAME': 'cltbld', 11:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:34 INFO - 'MOZ_NO_REMOTE': '1', 11:17:34 INFO - 'NO_EM_RESTART': '1', 11:17:34 INFO - 'PAGER': '/bin/cat', 11:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:34 INFO - 'PWD': '/builds/slave/test', 11:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:34 INFO - 'SHELL': '/bin/bash', 11:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:34 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:34 INFO - 'USER': 'cltbld', 11:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:34 INFO - 'XPC_FLAGS': '0x0', 11:17:34 INFO - 'XPC_SERVICE_NAME': '0', 11:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:34 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 11:17:34 INFO - Cleaning up... 11:17:34 INFO - Return code: 0 11:17:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:17:34 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')]} 11:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:34 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')]} 11:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:34 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:34 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>=3.1.1'] in /builds/slave/test/build 11:17:34 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>=3.1.1 11:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:34 INFO - 'HOME': '/Users/cltbld', 11:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:34 INFO - 'LOGNAME': 'cltbld', 11:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:34 INFO - 'MOZ_NO_REMOTE': '1', 11:17:34 INFO - 'NO_EM_RESTART': '1', 11:17:34 INFO - 'PAGER': '/bin/cat', 11:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:34 INFO - 'PWD': '/builds/slave/test', 11:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:34 INFO - 'SHELL': '/bin/bash', 11:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:34 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:34 INFO - 'USER': 'cltbld', 11:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:34 INFO - 'XPC_FLAGS': '0x0', 11:17:34 INFO - 'XPC_SERVICE_NAME': '0', 11:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:35 INFO - Downloading/unpacking psutil>=3.1.1 11:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:17:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:38 INFO - Installing collected packages: psutil 11:17:38 INFO - Running setup.py install for psutil 11:17:38 INFO - building 'psutil._psutil_osx' extension 11:17:38 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 11:17:38 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 11:17:38 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 11:17:38 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 11:17:38 INFO - building 'psutil._psutil_posix' extension 11:17:38 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 11:17:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:17:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:17:38 INFO - ^ 11:17:39 INFO - 1 warning generated. 11:17:39 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 11:17:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:39 INFO - Successfully installed psutil 11:17:39 INFO - Cleaning up... 11:17:39 INFO - Return code: 0 11:17:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:17: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')]} 11:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17: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')]} 11:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:39 INFO - 'HOME': '/Users/cltbld', 11:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:39 INFO - 'LOGNAME': 'cltbld', 11:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:39 INFO - 'MOZ_NO_REMOTE': '1', 11:17:39 INFO - 'NO_EM_RESTART': '1', 11:17:39 INFO - 'PAGER': '/bin/cat', 11:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:39 INFO - 'PWD': '/builds/slave/test', 11:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:39 INFO - 'SHELL': '/bin/bash', 11:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:39 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:39 INFO - 'USER': 'cltbld', 11:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:39 INFO - 'XPC_FLAGS': '0x0', 11:17:39 INFO - 'XPC_SERVICE_NAME': '0', 11:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:17:42 INFO - Installing collected packages: mozsystemmonitor 11:17:42 INFO - Running setup.py install for mozsystemmonitor 11:17:42 INFO - Successfully installed mozsystemmonitor 11:17:42 INFO - Cleaning up... 11:17:42 INFO - Return code: 0 11:17:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:17:42 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')]} 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 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')]} 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:42 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==2.5.1'] in /builds/slave/test/build 11:17:42 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==2.5.1 11:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:42 INFO - 'HOME': '/Users/cltbld', 11:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:42 INFO - 'LOGNAME': 'cltbld', 11:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:42 INFO - 'MOZ_NO_REMOTE': '1', 11:17:42 INFO - 'NO_EM_RESTART': '1', 11:17:42 INFO - 'PAGER': '/bin/cat', 11:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:42 INFO - 'PWD': '/builds/slave/test', 11:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:42 INFO - 'SHELL': '/bin/bash', 11:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:42 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:42 INFO - 'USER': 'cltbld', 11:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:42 INFO - 'XPC_FLAGS': '0x0', 11:17:42 INFO - 'XPC_SERVICE_NAME': '0', 11:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:42 INFO - Downloading/unpacking jsonschema==2.5.1 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:17:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:17:45 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 11:17:45 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 11:17:45 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 11:17:45 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 11:17:45 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 11:17:45 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 11:17:45 INFO - Downloading functools32-3.2.3-2.tar.gz 11:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:17:45 INFO - warning: no files found matching '*.txt' 11:17:45 INFO - no previously-included directories found matching 'build' 11:17:45 INFO - no previously-included directories found matching 'dist' 11:17:45 INFO - no previously-included directories found matching '.git*' 11:17:45 INFO - Installing collected packages: jsonschema, functools32 11:17:45 INFO - Running setup.py install for functools32 11:17:45 INFO - warning: no files found matching '*.txt' 11:17:45 INFO - no previously-included directories found matching 'build' 11:17:45 INFO - no previously-included directories found matching 'dist' 11:17:45 INFO - no previously-included directories found matching '.git*' 11:17:45 INFO - Successfully installed jsonschema functools32 11:17:45 INFO - Cleaning up... 11:17:45 INFO - Return code: 0 11:17:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:17:45 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')]} 11:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:45 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')]} 11:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:45 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:45 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 11:17:45 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 11:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:45 INFO - 'HOME': '/Users/cltbld', 11:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:45 INFO - 'LOGNAME': 'cltbld', 11:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:45 INFO - 'MOZ_NO_REMOTE': '1', 11:17:45 INFO - 'NO_EM_RESTART': '1', 11:17:45 INFO - 'PAGER': '/bin/cat', 11:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:45 INFO - 'PWD': '/builds/slave/test', 11:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:45 INFO - 'SHELL': '/bin/bash', 11:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:45 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:45 INFO - 'USER': 'cltbld', 11:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:45 INFO - 'XPC_FLAGS': '0x0', 11:17:45 INFO - 'XPC_SERVICE_NAME': '0', 11:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:46 INFO - Downloading/unpacking blobuploader==1.2.4 11:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:48 INFO - Downloading blobuploader-1.2.4.tar.gz 11:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:17:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:17:48 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 11:17:48 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 11:17:48 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 11:17:48 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 11:17:48 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 11:17:48 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 11:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:17:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:49 INFO - Downloading docopt-0.6.1.tar.gz 11:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:17:49 INFO - Installing collected packages: blobuploader, requests, docopt 11:17:49 INFO - Running setup.py install for blobuploader 11:17:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:17:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:17:49 INFO - Running setup.py install for requests 11:17:50 INFO - Running setup.py install for docopt 11:17:50 INFO - Successfully installed blobuploader requests docopt 11:17:50 INFO - Cleaning up... 11:17:50 INFO - Return code: 0 11:17:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:50 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')]} 11:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:50 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')]} 11:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:50 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:50 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 11:17:50 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 11:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:50 INFO - 'HOME': '/Users/cltbld', 11:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:50 INFO - 'LOGNAME': 'cltbld', 11:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:50 INFO - 'MOZ_NO_REMOTE': '1', 11:17:50 INFO - 'NO_EM_RESTART': '1', 11:17:50 INFO - 'PAGER': '/bin/cat', 11:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:50 INFO - 'PWD': '/builds/slave/test', 11:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:50 INFO - 'SHELL': '/bin/bash', 11:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:50 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:50 INFO - 'USER': 'cltbld', 11:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:50 INFO - 'XPC_FLAGS': '0x0', 11:17:50 INFO - 'XPC_SERVICE_NAME': '0', 11:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:50 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-KdREKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-GX8Ieg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-02sYiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-720eJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Z3ahyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-SWGltF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-vDF3M2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-bTg0NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-F_YOU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-lKdMtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:17:51 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-vpAQns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4zvm9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-gIFxkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-N70Tgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Ykqz6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-L30wDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:17:52 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-g9OTB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:17:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:17:52 INFO - Running setup.py install for manifestparser 11:17:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Running setup.py install for mozcrash 11:17:52 INFO - Running setup.py install for mozdebug 11:17:53 INFO - Running setup.py install for mozdevice 11:17:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozfile 11:17:53 INFO - Running setup.py install for mozhttpd 11:17:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozinfo 11:17:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozInstall 11:17:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozleak 11:17:53 INFO - Running setup.py install for mozlog 11:17:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Running setup.py install for moznetwork 11:17:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Running setup.py install for mozprocess 11:17:54 INFO - Running setup.py install for mozprofile 11:17:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Running setup.py install for mozrunner 11:17:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Running setup.py install for mozscreenshot 11:17:54 INFO - Running setup.py install for moztest 11:17:55 INFO - Running setup.py install for mozversion 11:17:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:17:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:17:55 INFO - Cleaning up... 11:17:55 INFO - Return code: 0 11:17:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:55 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')]} 11:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:55 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')]} 11:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:55 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:17:55 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 11:17:55 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 11:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:55 INFO - 'HOME': '/Users/cltbld', 11:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:55 INFO - 'LOGNAME': 'cltbld', 11:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:55 INFO - 'MOZ_NO_REMOTE': '1', 11:17:55 INFO - 'NO_EM_RESTART': '1', 11:17:55 INFO - 'PAGER': '/bin/cat', 11:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:55 INFO - 'PWD': '/builds/slave/test', 11:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:55 INFO - 'SHELL': '/bin/bash', 11:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:17:55 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:55 INFO - 'USER': 'cltbld', 11:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:55 INFO - 'XPC_FLAGS': '0x0', 11:17:55 INFO - 'XPC_SERVICE_NAME': '0', 11:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:55 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-B4iL8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:55 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)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:55 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-3YZ3wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:55 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)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:55 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-V5cwXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:55 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)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:55 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-YNgRjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:55 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)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:55 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-XqG7XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-QolVcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-lcQtyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-a6sa8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-dvseEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-eXfNpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-SC8Px4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-d8Y_PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-39NOgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:17:56 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)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:17:56 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4wlBDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:17:57 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)) 11:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:57 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-HkotbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:57 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)) 11:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:17:57 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-XlDTle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:17:57 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)) 11:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:17:57 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-NGoeYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:17:57 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)) 11:17:57 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)) 11:17:57 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)) 11:17:57 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)) 11:17:57 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)) 11:17:57 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)) 11:17:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:17:57 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 11:17:57 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 11:17:57 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 11:17:57 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 11:17:57 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 11:17:57 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 11:17:59 INFO - Downloading blessings-1.6.tar.gz 11:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:17:59 INFO - Installing collected packages: blessings 11:17:59 INFO - Running setup.py install for blessings 11:17:59 INFO - Successfully installed blessings 11:17:59 INFO - Cleaning up... 11:18:00 INFO - Return code: 0 11:18:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:18:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:00 INFO - Reading from file tmpfile_stdout 11:18:00 INFO - Current package versions: 11:18:00 INFO - blessings == 1.6 11:18:00 INFO - blobuploader == 1.2.4 11:18:00 INFO - docopt == 0.6.1 11:18:00 INFO - functools32 == 3.2.3-2 11:18:00 INFO - jsonschema == 2.5.1 11:18:00 INFO - manifestparser == 1.1 11:18:00 INFO - mozInstall == 1.12 11:18:00 INFO - mozcrash == 0.17 11:18:00 INFO - mozdebug == 0.1 11:18:00 INFO - mozdevice == 0.48 11:18:00 INFO - mozfile == 1.2 11:18:00 INFO - mozhttpd == 0.7 11:18:00 INFO - mozinfo == 0.9 11:18:00 INFO - mozleak == 0.1 11:18:00 INFO - mozlog == 3.2 11:18:00 INFO - moznetwork == 0.27 11:18:00 INFO - mozprocess == 0.22 11:18:00 INFO - mozprofile == 0.28 11:18:00 INFO - mozrunner == 6.11 11:18:00 INFO - mozscreenshot == 0.1 11:18:00 INFO - mozsystemmonitor == 0.3 11:18:00 INFO - moztest == 0.7 11:18:00 INFO - mozversion == 1.4 11:18:00 INFO - psutil == 3.1.1 11:18:00 INFO - requests == 1.2.3 11:18:00 INFO - wsgiref == 0.1.2 11:18:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18:00 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')]} 11:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:00 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')]} 11:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:00 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:18:00 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 11:18:00 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 11:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:00 INFO - 'HOME': '/Users/cltbld', 11:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:00 INFO - 'LOGNAME': 'cltbld', 11:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:00 INFO - 'MOZ_NO_REMOTE': '1', 11:18:00 INFO - 'NO_EM_RESTART': '1', 11:18:00 INFO - 'PAGER': '/bin/cat', 11:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:00 INFO - 'PWD': '/builds/slave/test', 11:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:00 INFO - 'SHELL': '/bin/bash', 11:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:18:00 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:00 INFO - 'USER': 'cltbld', 11:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:00 INFO - 'XPC_FLAGS': '0x0', 11:18:00 INFO - 'XPC_SERVICE_NAME': '0', 11:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 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)) 11:18:00 INFO - Cleaning up... 11:18:00 INFO - Return code: 0 11:18:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:18:00 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')]} 11:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:00 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')]} 11:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:00 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 0x10796ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a03618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadc057ff90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, '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.EzkcRCuYd5/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.pNtcoO55pB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 11:18:00 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 11:18:00 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 11:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:00 INFO - 'HOME': '/Users/cltbld', 11:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:00 INFO - 'LOGNAME': 'cltbld', 11:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:01 INFO - 'MOZ_NO_REMOTE': '1', 11:18:01 INFO - 'NO_EM_RESTART': '1', 11:18:01 INFO - 'PAGER': '/bin/cat', 11:18:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:01 INFO - 'PWD': '/builds/slave/test', 11:18:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:01 INFO - 'SHELL': '/bin/bash', 11:18:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:18:01 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:18:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:01 INFO - 'USER': 'cltbld', 11:18:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:01 INFO - 'XPC_FLAGS': '0x0', 11:18:01 INFO - 'XPC_SERVICE_NAME': '0', 11:18:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:18:01 INFO - Cleaning up... 11:18:01 INFO - Return code: 0 11:18:01 INFO - Running post-action listener: _resource_record_post_action 11:18:01 INFO - Running post-action listener: _start_resource_monitoring 11:18:01 INFO - Starting resource monitoring. 11:18:01 INFO - ##### 11:18:01 INFO - ##### Finished create-virtualenv step (success) 11:18:01 INFO - ##### 11:18:01 INFO - ##### 11:18:01 INFO - ##### Running install step. 11:18:01 INFO - ##### 11:18:01 INFO - Running pre-action listener: _resource_record_pre_action 11:18:01 INFO - Running main action method: install 11:18:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:01 INFO - Reading from file tmpfile_stdout 11:18:01 INFO - Detecting whether we're running mozinstall >=1.0... 11:18:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:18:01 INFO - Reading from file tmpfile_stdout 11:18:01 INFO - Output received: 11:18:01 INFO - Usage: mozinstall [options] installer 11:18:01 INFO - Options: 11:18:01 INFO - -h, --help show this help message and exit 11:18:01 INFO - -d DEST, --destination=DEST 11:18:01 INFO - Directory to install application into. [default: 11:18:01 INFO - "/builds/slave/test"] 11:18:01 INFO - --app=APP Application being installed. [default: firefox] 11:18:01 INFO - mkdir: /builds/slave/test/build/application 11:18:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:18:35 INFO - Reading from file tmpfile_stdout 11:18:35 INFO - Output received: 11:18:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:18:35 INFO - Running post-action listener: _resource_record_post_action 11:18:35 INFO - ##### 11:18:35 INFO - ##### Finished install step (success) 11:18:35 INFO - ##### 11:18:35 INFO - ##### 11:18:35 INFO - ##### Running run-tests step. 11:18:35 INFO - ##### 11:18:35 INFO - Running pre-action listener: _resource_record_pre_action 11:18:35 INFO - Running main action method: run_tests 11:18:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:18:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:18:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:18:35 INFO - 2016-07-04 11:18:35.777 screenresolution[1658:7704] starting screenresolution argv=screenresolution get 11:18:35 INFO - 2016-07-04 11:18:35.780 screenresolution[1658:7704] Display 0: 1600x1200x32@60 11:18:35 INFO - 2016-07-04 11:18:35.787 screenresolution[1659:7708] starting screenresolution argv=screenresolution list 11:18:35 INFO - Available Modes on Display 0 11:18:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:18:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:18:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:18:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:18:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:18:36 INFO - Intel Iris: 11:18:36 INFO - Chipset Model: Intel Iris 11:18:36 INFO - Type: GPU 11:18:36 INFO - Bus: Built-In 11:18:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:18:36 INFO - Vendor: Intel (0x8086) 11:18:36 INFO - Device ID: 0x0a2e 11:18:36 INFO - Revision ID: 0x0009 11:18:36 INFO - Displays: 11:18:36 INFO - Display: 11:18:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:18:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:18:36 INFO - Display Serial Number: 1600x1200 60 11:18:36 INFO - Main Display: Yes 11:18:36 INFO - Mirror: Off 11:18:36 INFO - Online: Yes 11:18:36 INFO - Rotation: Supported 11:18:36 INFO - Return code: 0 11:18:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:18:36 INFO - Python 2.7.3 11:18:36 INFO - Return code: 0 11:18:36 INFO - grabbing minidump binary from tooltool 11:18:36 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')]} 11:18:36 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078d2d30>, '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 0x107637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078cab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:18:36 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 11:18:36 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 11:18:36 INFO - Calling ['/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'] with output_timeout 600 11:18:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:18:36 INFO - Return code: 0 11:18:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:18:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:18:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:18:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:18:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:18:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0339 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzkcRCuYd5/Render', 11:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:36 INFO - 'HOME': '/Users/cltbld', 11:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:36 INFO - 'LOGNAME': 'cltbld', 11:18:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:18:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:36 INFO - 'MOZ_NO_REMOTE': '1', 11:18:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:18:36 INFO - 'NO_EM_RESTART': '1', 11:18:36 INFO - 'PAGER': '/bin/cat', 11:18:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:36 INFO - 'PWD': '/builds/slave/test', 11:18:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:36 INFO - 'SHELL': '/bin/bash', 11:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNtcoO55pB/Listeners', 11:18:36 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 11:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:36 INFO - 'USER': 'cltbld', 11:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:36 INFO - 'XPC_FLAGS': '0x0', 11:18:36 INFO - 'XPC_SERVICE_NAME': '0', 11:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:18:36 INFO - mozversion application_buildid: 20160704090224 11:18:36 INFO - mozversion application_changeset: f378a56b25ce2a2997b263c1857629f3f18d7400 11:18:36 INFO - mozversion application_display_name: Nightly 11:18:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:18:36 INFO - mozversion application_name: Firefox 11:18:36 INFO - mozversion application_remotingname: firefox 11:18:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:18:36 INFO - mozversion application_vendor: Mozilla 11:18:36 INFO - mozversion application_version: 50.0a1 11:18:36 INFO - mozversion platform_buildid: 20160704090224 11:18:36 INFO - mozversion platform_changeset: f378a56b25ce2a2997b263c1857629f3f18d7400 11:18:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:18:36 INFO - mozversion platform_version: 50.0a1 11:18:36 INFO - using testdate: 1467656316 11:18:36 INFO - actual date: 1467656316 11:18:36 INFO - starting webserver on 'localhost:49275' 11:18:36 INFO - SUITE-START | Running 2 tests 11:18:36 INFO - TEST-START | dromaeo_css 11:18:36 INFO - operating with platform_type : mac_ 11:18:36 INFO - Initialising browser for dromaeo_css test... 11:18:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpliZe2z/profile http://localhost:49275/getInfo.html) 11:18:38 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 11:18:38 INFO - PROCESS | 1669 | colorDepth:24 11:18:38 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 11:18:38 INFO - PROCESS | 1669 | __metrics 11:18:38 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:18:38 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:18:39 INFO - TEST-INFO | 1669: exit 0 11:18:39 INFO - Browser initialized. 11:18:39 INFO - Running cycle 1/1 for dromaeo_css test... 11:18:39 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpliZe2z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:18:51 INFO - PROCESS | 1674 | RSS: Main: 244858880 11:18:51 INFO - PROCESS | 1674 | 11:19:42 INFO - PROCESS | 1674 | 1467656382856 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] 11:21:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 11:21:54 INFO - PROCESS | 1674 | RSS: Main: 374423552 11:21:54 INFO - PROCESS | 1674 | 11:24:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 11:24:58 INFO - PROCESS | 1674 | RSS: Main: 379871232 11:24:58 INFO - PROCESS | 1674 | 11:28:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 11:28:01 INFO - PROCESS | 1674 | RSS: Main: 372838400 11:28:01 INFO - PROCESS | 1674 | 11:31:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 11:31:04 INFO - PROCESS | 1674 | RSS: Main: 371572736 11:31:04 INFO - PROCESS | 1674 | 11:34:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 11:34:08 INFO - PROCESS | 1674 | RSS: Main: 340832256 11:34:08 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 11:37:11 INFO - PROCESS | 1674 | RSS: Main: 387108864 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | __start_tp_report 11:37:11 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 11:37:11 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 11:37:11 INFO - PROCESS | 1674 | |i|pagename|runs| 11:37:11 INFO - PROCESS | 1674 | |0;dojo.html;3180.1890517917927;3374.780639258448;3394.8132852693093;3401.6733473945164;3402.4726167444046;5658.43415658434;5692.37383887772;5798.594098413113;5799.507041901438;5860.413958604137;5735.684537350444;5796.391378905218;5886.411358864111;5891.204687367204;5906.143609176666;5131.563817075553;5200.375954885419;5254.842354729345;5267.341582302212;5394.568434525228;5345.33183352081;5368.677879327233;5394.05604019297;5397.46025397461;5419.430959749228;5187.896242075156;5220.060389129955;5256.816011439581;5271.341082364705;5290.365156181244;4741.857744267678;5073.416557095908;5250.107481728115;5271.973640131775;5279.9208011879855;5268;5269.420363759983;5309.5752339812725;5315.963387139509;5345.759440825133;4852.344933433977;5661.377248502661;5688.544916406719;5835.328937172237;5903.911441328383;6196.101565273055;6267.5299352548745;6289.842753931142;6297.559170858041;6322.241331040259;5047.823326183603;5802.071668533056;6123.632582045091;6382.297947225801;6395.52033597482;5729.713514324267;5787.334456537552;5895.292564892197;5925.229720136398;5939.168516407663;6306.873862522815;6359.904601430982;6367.4906007519285;6384;6393.648349340738;6262.12330273762;6265.937340626627;6274.215723034621;6287;6294.244690637152;6198.659073750987;6229.719662615194;6306.463950564162;6354.078658594478;6388.2653494847755;6273.059041143773;6306.621602703853;6355.777547785805;6361.777337793247;6369.108324834527;6380.330065343072;6386;6386.201724784402;6387.169667943138;6400.0719895614875;4884.291777692215;4936.531029552187;4970.15507363749;4977.676451030671;5032.144535428986;4436.800343984464;4447.355133505624;4463.732176069446;4486.192485352668;4504.009117994036;5797;5816.534677225812;5822.184894114827;5826.359100498942;5881.9705901470215;6253.5622506424115;6281.277653069956;6287.402696743802;6351.777687780998;6354.4281014708895;5795.304563452412;5842.970785146132;5846.941530584725;5850.297964244232;5858.150568167592;5520.3651580068;5521.972390138024;5522.475364840334;5526.47498487643;5533.695646739468;6276.309605943343;6311.999999999908;6343.460805831556;6369.968150159405;6440.001799721051;6354.872902542016;6376.617402955838;6387.489000880011;6388.8083357499345;6411.326810684997;6458.61248325102;6460.192475940507;6464.030395440722;6468.708908099053;6590.373914478117;6134.04922237054;6197.752089916353;6218.471429928507;6262.029385445263;6326.9683651581445;6338.302786693553;6374;6391.169148010915;6401.775937842153;6413.230412350361;6028.728707208274;6130.141780150871;6212.627242365473;6237.875242495215;6244.344343843831;6122.520135281246;6125.173101631222;6153.815385538441;6228.968855155877;6239.126522286974;6032.909506357496;6035.728392222272;6040.054891509743;6066.4540191382985;6086.513078953762;6040.6677632730625;6089.025755879038;6165.630062196283;6197.659128747962;6222.906656400248;5718.228039214653;5860.794872179454;5976.760929562769;5985.760569577168;6092.908606370819 11:37:11 INFO - PROCESS | 1674 | |1;ext.html;483.3716168980326;488.68968205189697;502.55272807201595;502.9597632189425;505.6511007404888;29751.55372669409;41902.16195676089;42504.93737656556;42686.57313426865;44966.32550511745;41989.58010419895;42866.7856660717;45194.774026129824;45377.54622453774;45476.63570092894;8731.95634021829;9673.61305547779;9829.164521015722;9848.753781155492;9863.852042219372;8742.606582703762;11821;12004.339761313124;12021.278723276588;12328;11983.400829958468;12048.879511204863;12056.758864822697;12060.457279422448;12089.939550302237;7798.025246844088;8781.912180878173;8969;11909.344986025764;12086.093542984267;8840.027596964328;11452.484638191301;11718.296902185895;11727.824082638766;11985.520579176822;42643.57356426427;43004;43079.7846010768;43193;43353.566464335265;3528.5942116656656;3596.7662101963288;3876.8449262029485;4219.578042195786;4242.639375653073;32568.348633027443;33233.6676633233;33658.831705841316;34074.318513629834;34256.82871585651;3241.837908104609;3296.620888597794;3296.6373698893103;3404.540387047766;3411.385950528904;534184;536548;538040;538291;545023;65220.673896630215;65555.01667474992;65619.67190164019;65731.38477383694;65823;68762.65618671874;69472.95790563145;69520;69572.65213674;69827.65086174537;16702.164891755365;17088.57278568046;17134.914325428294;17165.141742912805;17174.96950182968;11105.333679979227;11800.937915587638;12062.638120856389;12076.456559454846;12239.265644061385;14937;16327.510174694778;17886;18240.45278641643;18313.450596482126;6464.256610489854;7506.662200201004;7559.508631938907;7640.471905618899;7738.92261077382;901.9233365164034;957.3537861943161;989.0752146742818;996.4071377530358;1023.2019025160387;27862;28841.85579072091;30659;31383;32233;959.8608201810698;1079.7462596289865;1130.6890605083668;1134.8127558952845;1150.9597164099216;15330.658819650289;16189;16462.75305870413;16647.750283745754;16883.662326753398;553972.460275392;588472;589944;591021;592713.4371937697;61739;62403;62490;62595.748085038955;62634;1286.8327117474669;1342.0940864916338;1435.8923080768984;1440.9567712968628;1452.3682198243798;25156.49687006213;30734.53898191484;33289.334213316084;34068.488972664934;34185.145371365914;61227.77544449175;61845;61877;62145.689271553354;62179.689101554206;623358;644471;649060;649137;649959;36740.44889326609;37423.25153496969;41103.58896410998;43421.78289108534;44075.77962110169;51137.744311278206;51147.885211478555;51252.74373628108;52078.21882671839;52185.95628087493;33014.66985330212;40494.59505405026;42254;42307.36538951856;43411.34882976695;41348.793256033525;41672.583274168086;41678.79160604178;42755;42780;40118.99702507344;40172.799136004134;41427.79286103671;41524.96187595334;43481.78259108684;34026.659733403336;36608;37112.81443592765;37118;37379;22108.2262120825;26227;26355.736442636095;26583.734162658126;26627.733722662524 11:37:11 INFO - PROCESS | 1674 | |2;jquery.html;378.97157713171515;381.91025109099377;386.5921452867226;399.4467662287731;400.46137944464704;19163.329283475083;20978.160873565077;21245.362639120813;21372.57254854904;21376.144954201834;10338;11881.7623647527;12385.938070309636;12390.93804530976;20447.591048179063;5310.787568497255;5493.285872836525;5551.306086739158;5556.222128901957;5558.027345214591;3964.7224694271413;4096.692748043909;4419.138268037734;6789.490788190881;6813.250542440328;6781.287964763703;6786.049953006583;6840.213375461811;6842.384185423311;6846.4865135114815;6824.658767061652;6825.726970921157;6827.829304267383;6829.556078854858;6834.726610935581;3845.3462911305146;4071.226466971266;5628.380878103405;5950.702464876782;5954.612950158225;13368.264745438997;13383.799243011363;13687.178769273776;13689.726205475934;13775.38010789517;2169.2971017751147;2418.8147807574337;2436.6832311799353;2491.5639763041577;2998.7750918681186;9747.658831940918;10124.89875101247;10430.478476076241;10434.9478252609;10685.35887846724;1700.1159397113431;1852.2868695552174;1887.811218878115;1894.668433024229;1906.780720217179;90740.6388904167;95396.52301738448;122312;127968;135742.999999999;9936.652217172365;10137.493125343703;11395.772084558263;11556.537738490535;11905.880941190651;12694.49222031127;13641.454341826216;14740.41038358475;14839.10965342083;14875.851241487446;8636.740897773077;8784.60469278884;8847.42491738035;8887.688930887389;8922.464652120894;8617.913820861711;8823.602937867812;8827.602757875911;8978.955105224562;9051.728448146567;3755.8497660093835;4604.1712491751805;4631.861044168681;4634.397528321297;4699.694519856198;1627.894186877849;1660.4022551881312;1935.6225536020622;1950.590376021023;1951.9121639526256;810.8769354444138;925.3707478914268;935.4675853057324;945.5933948402115;997.7006897930592;21124;21702.674459883114;21773.45566360822;21790.455238618844;21966.670499942513;924.7965447601517;952.3333666433385;998.5706146357136;1003.5734812704571;1021.4994652620163;11343.659690209306;11412.144089193178;11430.028447582048;11563.30620162793;11665.708357291136;593789;604833;617412;624472;625605;126989.82525436938;129566;130745;133650;135294.32352838173;964.4791812421354;1011.5953618552638;1012.7468132966758;1083.128081894067;1083.8320060390652;18102.637947241245;18966.24135034583;19239.90380048147;19304.32434864773;19370.934598597214;39384.424623015904;50483.495165047876;54286;56528.434715652314;56618.43381566297;76789.61605191938;79018;79996;81179.6938275558;81634.18365816266;7972.162922893012;8178.550179740171;8342.249197572248;8355.707550235713;8471.32229421657;9333.393329433566;10962.671119866416;13245.27151006704;13364.264965426995;13366.398512067175;11176.776464470828;11476.827847582126;11574.016208622024;11584.652460426201;11605.825912611148;8951.373403861668;10955.233133680566;11152.275102118336;11184.827503641518;11331.716707082389;11672.066234701373;11686.532538698697;11793.587224447103;11881.702957426134;11902.40487975615;6961.582305061713;8239.546824924686;8270.83458330842;8455.95772021136;8465.06884242745;10494.895051049392;11485.655430337103;11643;11645.184837061324;11667.533298667959 11:37:11 INFO - PROCESS | 1674 | |3;mootools.html;1402.845686974432;1561.125769569041;1592.5063230398573;1807.4939017075214;1949.3469687654626;7912.723054693087;8706.085860984593;8965.10348965104;8969.237614802732;9098.681546145886;8760.123987601237;8955;8961.32790040748;8961.924569051707;8981.910180898189;2120.5016821047043;2189.419803751999;2325.104834638668;2374.6319320505304;2408.759124087595;2387.617981123021;2430.4895971845935;2433.124075332879;2433.4159801647565;2440.6095024796036;2358.363241924679;2388.7372389037278;2405.7113146422366;2405.8797060147012;2408.217329367954;2102.148629804924;2305.8501197422115;2369.9052037918464;2371.905123795046;2378.2389635316704;2357.351728274738;2380.345405013618;2383.487550176703;2385.093664407514;2391.9521609567887;8942.239909607692;8992.730218093468;9020.594073266653;9028.50343231122;9031.096890310982;5259.973700131475;5268.367795864521;5418.512333889968;5421.403645598981;5432.972835135839;3578.588462326814;3626.3291291111227;3684.44733290006;3684.9815750921343;3697.6856967157823;1043.7286305560533;1076.0960792933895;1100.5322737836389;1113.7271368514685;1114.8773634900153;11889.097744360948;11891.464884080237;11899.524019039229;11904.16670833042;11918.046556275478;4608.28571571407;4616.422947131608;4853.223484242575;4854.83008094715;4865.781039853215;10012.849807252896;10018.248631352531;10020.448875311782;10024.097831195082;10033.197344212445;5412.756425960842;5419;5427.3215848019;5427.3215848019;5427.48438898304;4447.132809102259;4599.264117741145;4799.352087468217;4909.607231421478;4917.9016419672125;3937.448757173998;4006.2988976928923;4058.898527536835;4085.3871919212356;4123.670106391482;1196.2642974570576;1296.2028352563295;1302.9022823288292;1304.4456106155035;1315.0268801087318;816.3387655998661;939.5865819039601;947.3179310896144;962.5091203486272;972.3922548407245;6002.669853158118;6076.422739839708;6078.787242446493;6101.054336577838;6196.43316640606;898.6360523987795;960.4333443268505;976.5947131940308;1025.7179275699093;1026.3174988632625;3989.5810939851476;4030.5163380394542;4048.554658987568;4126.154138401621;4148.211839750498;10486.056254937092;10700.89299106999;10778.568857245778;10809.621663241749;10812.297200681931;10667.093297069681;10742.677719668422;10774.569017239379;10776.515056822462;10779.407132607781;7836.725714599962;8255.711050113217;8259.545724985064;8394.58027098655;8398.580070996426;5059.494050594911;5835.212246346859;5974.880502390015;6132.41742034506;6241.843953901189;9899.109080182818;10140.695779126638;10200.591976320864;10202.030807073315;10235.897641023494;9707.514624268617;10068.798624027331;10086.243531735003;10246.846297305696;10269.84595231087;6953.269906659728;7181.533200341961;7404.888926665996;7460.515066520658;7699.8075048122;8369.49783013021;8460.534670593177;8543.231109199953;8549.829003419773;8559.486430813922;7545.6981720730555;7667.923320766721;7724.227577242231;7806.1803510632835;7806.960965195138;7161.355478007004;7187.640617969185;7404.740834070782;7637.350825179922;7650.273224043707;7090.822729431806;7150.606716630636;7165.928340716526;7172.713091476491;7178.820529486805;4912.312276281395;5027.4218464877;5048.823291184791;5129.538341549279;5144.871378215425;5073.531966427883;5148.150555158357;5181.35233095863;5211.973940130275;5250.65870718402 11:37:11 INFO - PROCESS | 1674 | |4;prototype.html;305.1395065914123;308.8841684368362;318.6240236520905;323.3339320998744;323.33877221082884;4891.850415152437;5190.351206099238;5388.568914486841;5437.308532276524;5479.808206712766;4237.682173836959;5158.200478925773;5368.892622147565;5383.273258110154;5420.728963551826;4121.340585506319;4130.834766609347;4151.813168407414;4165.666746660268;4192.49690037196;3329.816860072695;3826.65560099591;4050.5949405059405;4174.332106862903;4182.16356728654;3414.265932824442;4114.128071727372;4118.238125946709;4122.4228607994755;4136.544980052192;3592.8562857485667;3908.9413658795143;4079.8572049978106;4109.958900410988;4122.773247471388;3528.3648943190215;3573.857045718168;3582.498450216972;3600.117971097098;3632.7820330780237;5119.385673719177;5144.871378215537;5169.612279079084;5282.630215884889;5291.576673866081;4295.720778149442;4459.773562270369;4685.672002959795;4706.411698537683;4722.244440889475;5500.487365974389;5565.1095824667855;5617.662940223599;5619.690916999563;5640.407757185519;3243.3189030303665;3535.3106144301873;3938.369860822255;3950.071733142709;3992.7803970781597;4895.461499235082;5298.516415403693;5676.801311954103;5819.359870414251;5949;4033.153037862082;4047.5750046245307;4054.0675644601906;4068.8575899843954;4072.714909956334;3444.8794292199655;3740.588535261119;3991.6806655467494;4037.858674946363;4097.815598298083;4634.516954574504;4647.233206520887;4671.48613652498;4672.112298663243;4678.274867395559;5021.774020169101;5274.446183150791;5737.5983681141915;5750.654960702372;5806.2451881255165;1114.8494953181216;1139.6865861888048;1272.6500212441467;1298.9870101299055;1364.8089267502558;802.2579114319232;821.2526600793369;899.5682072605186;918.8621706744042;923.5105394140982;4920.696015555881;5017.974910125499;5194.22086687;5194.97402512985;5204.713740744295;4799.784009719571;5496.697681627549;5716.314042314949;5741.282339707536;5757.280339957505;4921.458639549646;5217.634765566374;5219.947800522022;5263.6315457918345;5264.814787053854;2080.937571872846;2479.429731161807;2519.9164359325664;2523.4953009398196;2537.314924970248;4374.162851602335;4879.4385796545475;4886.804527818918;5009.924851127236;5089.262057001788;4319.784010799447;4527.569880861313;4649.814007439664;4767.69010014348;4926.827561035347;4135.503739551213;4427.690061695715;4565.703229290219;5019.297298378303;5034.496550345009;4227.408162857264;4620.798592365942;4809.831655892167;4815.542523460265;4827.927581086356;4104;4725.983913458623;4772.969164580586;4902.950970490249;5211.140161873249;5117.923231151462;5203.479652034917;5493.560515158858;6108.144859719554;6177.938220617736;3462.2902305027915;3828.559715632738;3936.468576742218;4002.3996400538986;4004.719669623215;5037.92443113361;5169.276301317744;5897.3807750185615;5968.820935371772;6026.548008899263;4774.466894498116;5231.476852314738;5714.3999280453045;5722.370539240597;5751.079827227623;5685.71571421419;5720.8569785754025;5721.22763426932;5738.102258359501;5740.339860915881;5102.300831896845;5219.556337711185;5396.811111611232;5607.635503692247;5651.050387616266;5638.23883775685;5730.455606717355;5736.6558006519745;5739.5695322850115;5742.569307302053;5055.292259083658;5248.60635452335;5535.225068490334;5580.441955804387;5611.915821262603 11:37:11 INFO - PROCESS | 1674 | |5;yui.html;725.8439435521364;746.9514481558698;760.5132715062358;765.7664412354231;790.6363072986426;14914.105153690785;15737.84262157378;16342.83657163428;16374.345026198953;16386.83613163868;14765.335559899804;14963.326650300736;14968.775468367983;14970.32633531491;15048.322825472853;726.6838925067586;755.161770434817;763.7975936376864;885.9689910853122;922.5110691333604;741.0144507804633;743.2418932688654;743.8884167374883;882.0297672560197;921.9720012186398;955.2692190474293;959.6593209410663;961.1974001708581;963.3401120232663;963.7542426681186;948.3598570964572;948.6869333120054;951.728757304172;952.5142177489496;962.4946902876011;566.5326105962597;581.5318668471879;581.953443724501;584.9327327357366;722.7145277615365;14682.412703491847;15253.542393728207;15260.465883694129;15268.236588170546;15286.235688215545;12087.637370878887;14198.85801141996;15127.092374457568;15161.620959475991;15328;12051.879481205251;12074.69813254667;12113.576024839089;12252.632421027383;12266;3876.127871228979;3957.8614748483665;3985.123272879991;4098.221337945811;4102.2410853991805;77712;98415.50792246065;101018;114730.99999999917;114938;11491.367974761468;11531.596394126165;11726.472308746126;12135.514579416724;12186.451609677586;24083.879580602334;24361.14735984232;24374.634380484305;24390.390240244134;24397.756022439902;8281.792955176048;8339.499630022217;8383.58082095905;8515.701950431705;8520.829583408296;4288.52826189119;4530.61489773373;4551.385562949025;4610.654200934943;4823.517648235219;3595.406757884972;4036.334004889219;4210.957890421118;4213.831446742157;4247.4265974093105;710.0910834132319;738.7488253993561;753.5516367761173;764.4992529892929;766.4596459496042;486.7882471125072;622.5735371270694;630.854903372227;687.9508749157475;701.7017767448814;9240.89119274278;9671.564779584794;9689.27330450205;9856.310058295994;9882.061204185591;655.799791698853;658.5357323087212;675.1965161457849;692.8094773937207;717.1215261304964;4604.769761511978;4768.260919557474;4773.904521909542;4781.474037855833;4839.322494850724;135128;156338.21830891;161376;170984.1450792763;178555;144199;147956;149779;151948;153640;122666.38666806788;122773;123161.38419307845;123331;126040.36979815042;6622.503312251504;9153.725388238363;9245.815083698422;9247.657482372177;9395.460497599226;68236.31763682299;68636;69621;70541.6472917632;70624.99070175667;68290.95127146193;68653.65673171602;87515.1248487507;90349.54825226095;95774;13606.86393136056;13757.656058598615;14080.471166812122;14199.64500887486;14288.928555357572;15334.539963801104;15347.846521535088;15658.530244092695;15727.135007574694;15940.043597384194;14347.067440616327;14540.85459145395;14696.55910322692;14848.40606375733;15000;7694.653740581575;13029.543965961526;14039.929800350932;14427;14657.267136643339;13305.401256943267;13612.183269003892;13801.447942082204;14204.928975355471;14375.353109110325;11172.183056518115;11284.774304514029;11347;11779.234349767237;11798.941005294919;11250.455741847944;11415.714607134889;11636.650900473;11640.126990475917;11650.88349116498 11:37:11 INFO - PROCESS | 1674 | __end_tp_report 11:37:11 INFO - PROCESS | 1674 | __start_cc_report 11:37:11 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1283 11:37:11 INFO - PROCESS | 1674 | __end_cc_report 11:37:11 INFO - PROCESS | 1674 | __startTimestamp1467657431627__endTimestamp 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | ------- Summary: start ------- 11:37:11 INFO - PROCESS | 1674 | Number of tests: 6 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5812.44 Median:6032.91 stddev:668.45 (11.1%) stddev-sans-first:637.81 11:37:11 INFO - PROCESS | 1674 | Values: 3180.2 3374.8 3394.8 3401.7 3402.5 5658.4 5692.4 5798.6 5799.5 5860.4 5735.7 5796.4 5886.4 5891.2 5906.1 5131.6 5200.4 5254.8 5267.3 5394.6 5345.3 5368.7 5394.1 5397.5 5419.4 5187.9 5220.1 5256.8 5271.3 5290.4 4741.9 5073.4 5250.1 5272.0 5279.9 5268.0 5269.4 5309.6 5316.0 5345.8 4852.3 5661.4 5688.5 5835.3 5903.9 6196.1 6267.5 6289.8 6297.6 6322.2 5047.8 5802.1 6123.6 6382.3 6395.5 5729.7 5787.3 5895.3 5925.2 5939.2 6306.9 6359.9 6367.5 6384.0 6393.6 6262.1 6265.9 6274.2 6287.0 6294.2 6198.7 6229.7 6306.5 6354.1 6388.3 6273.1 6306.6 6355.8 6361.8 6369.1 6380.3 6386.0 6386.2 6387.2 6400.1 4884.3 4936.5 4970.2 4977.7 5032.1 4436.8 4447.4 4463.7 4486.2 4504.0 5797.0 5816.5 5822.2 5826.4 5882.0 6253.6 6281.3 6287.4 6351.8 6354.4 5795.3 5843.0 5846.9 5850.3 5858.2 5520.4 5522.0 5522.5 5526.5 5533.7 6276.3 6312.0 6343.5 6370.0 6440.0 6354.9 6376.6 6387.5 6388.8 6411.3 6458.6 6460.2 6464.0 6468.7 6590.4 6134.0 6197.8 6218.5 6262.0 6327.0 6338.3 6374.0 6391.2 6401.8 6413.2 6028.7 6130.1 6212.6 6237.9 6244.3 6122.5 6125.2 6153.8 6229.0 6239.1 6032.9 6035.7 6040.1 6066.5 6086.5 6040.7 6089.0 6165.6 6197.7 6222.9 5718.2 5860.8 5976.8 5985.8 6092.9 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:73881.79 Median:41790.48 stddev:157317.89 (376.4%) stddev-sans-first:157662.69 11:37:11 INFO - PROCESS | 1674 | Values: 483.4 488.7 502.6 503.0 505.7 29751.6 41902.2 42504.9 42686.6 44966.3 41989.6 42866.8 45194.8 45377.5 45476.6 8732.0 9673.6 9829.2 9848.8 9863.9 8742.6 11821.0 12004.3 12021.3 12328.0 11983.4 12048.9 12056.8 12060.5 12089.9 7798.0 8781.9 8969.0 11909.3 12086.1 8840.0 11452.5 11718.3 11727.8 11985.5 42643.6 43004.0 43079.8 43193.0 43353.6 3528.6 3596.8 3876.8 4219.6 4242.6 32568.3 33233.7 33658.8 34074.3 34256.8 3241.8 3296.6 3296.6 3404.5 3411.4 534184.0 536548.0 538040.0 538291.0 545023.0 65220.7 65555.0 65619.7 65731.4 65823.0 68762.7 69473.0 69520.0 69572.7 69827.7 16702.2 17088.6 17134.9 17165.1 17175.0 11105.3 11800.9 12062.6 12076.5 12239.3 14937.0 16327.5 17886.0 18240.5 18313.5 6464.3 7506.7 7559.5 7640.5 7738.9 901.9 957.4 989.1 996.4 1023.2 27862.0 28841.9 30659.0 31383.0 32233.0 959.9 1079.7 1130.7 1134.8 1151.0 15330.7 16189.0 16462.8 16647.8 16883.7 553972.5 588472.0 589944.0 591021.0 592713.4 61739.0 62403.0 62490.0 62595.7 62634.0 1286.8 1342.1 1435.9 1441.0 1452.4 25156.5 30734.5 33289.3 34068.5 34185.1 61227.8 61845.0 61877.0 62145.7 62179.7 623358.0 644471.0 649060.0 649137.0 649959.0 36740.4 37423.3 41103.6 43421.8 44075.8 51137.7 51147.9 51252.7 52078.2 52186.0 33014.7 40494.6 42254.0 42307.4 43411.3 41348.8 41672.6 41678.8 42755.0 42780.0 40119.0 40172.8 41427.8 41525.0 43481.8 34026.7 36608.0 37112.8 37118.0 37379.0 22108.2 26227.0 26355.7 26583.7 26627.7 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:35011.79 Median:12192.74 stddev:102365.59 (839.6%) stddev-sans-first:102619.72 11:37:11 INFO - PROCESS | 1674 | Values: 379.0 381.9 386.6 399.4 400.5 19163.3 20978.2 21245.4 21372.6 21376.1 10338.0 11881.8 12385.9 12390.9 20447.6 5310.8 5493.3 5551.3 5556.2 5558.0 3964.7 4096.7 4419.1 6789.5 6813.3 6781.3 6786.0 6840.2 6842.4 6846.5 6824.7 6825.7 6827.8 6829.6 6834.7 3845.3 4071.2 5628.4 5950.7 5954.6 13368.3 13383.8 13687.2 13689.7 13775.4 2169.3 2418.8 2436.7 2491.6 2998.8 9747.7 10124.9 10430.5 10434.9 10685.4 1700.1 1852.3 1887.8 1894.7 1906.8 90740.6 95396.5 122312.0 127968.0 135743.0 9936.7 10137.5 11395.8 11556.5 11905.9 12694.5 13641.5 14740.4 14839.1 14875.9 8636.7 8784.6 8847.4 8887.7 8922.5 8617.9 8823.6 8827.6 8979.0 9051.7 3755.8 4604.2 4631.9 4634.4 4699.7 1627.9 1660.4 1935.6 1950.6 1951.9 810.9 925.4 935.5 945.6 997.7 21124.0 21702.7 21773.5 21790.5 21966.7 924.8 952.3 998.6 1003.6 1021.5 11343.7 11412.1 11430.0 11563.3 11665.7 593789.0 604833.0 617412.0 624472.0 625605.0 126989.8 129566.0 130745.0 133650.0 135294.3 964.5 1011.6 1012.7 1083.1 1083.8 18102.6 18966.2 19239.9 19304.3 19370.9 39384.4 50483.5 54286.0 56528.4 56618.4 76789.6 79018.0 79996.0 81179.7 81634.2 7972.2 8178.6 8342.2 8355.7 8471.3 9333.4 10962.7 13245.3 13364.3 13366.4 11176.8 11476.8 11574.0 11584.7 11605.8 8951.4 10955.2 11152.3 11184.8 11331.7 11672.1 11686.5 11793.6 11881.7 11902.4 6961.6 8239.5 8270.8 8456.0 8465.1 10494.9 11485.7 11643.0 11645.2 11667.5 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5792.47 Median:4854.03 stddev:3232.18 (66.6%) stddev-sans-first:3224.41 11:37:11 INFO - PROCESS | 1674 | Values: 1402.8 1561.1 1592.5 1807.5 1949.3 7912.7 8706.1 8965.1 8969.2 9098.7 8760.1 8955.0 8961.3 8961.9 8981.9 2120.5 2189.4 2325.1 2374.6 2408.8 2387.6 2430.5 2433.1 2433.4 2440.6 2358.4 2388.7 2405.7 2405.9 2408.2 2102.1 2305.9 2369.9 2371.9 2378.2 2357.4 2380.3 2383.5 2385.1 2392.0 8942.2 8992.7 9020.6 9028.5 9031.1 5260.0 5268.4 5418.5 5421.4 5433.0 3578.6 3626.3 3684.4 3685.0 3697.7 1043.7 1076.1 1100.5 1113.7 1114.9 11889.1 11891.5 11899.5 11904.2 11918.0 4608.3 4616.4 4853.2 4854.8 4865.8 10012.8 10018.2 10020.4 10024.1 10033.2 5412.8 5419.0 5427.3 5427.3 5427.5 4447.1 4599.3 4799.4 4909.6 4917.9 3937.4 4006.3 4058.9 4085.4 4123.7 1196.3 1296.2 1302.9 1304.4 1315.0 816.3 939.6 947.3 962.5 972.4 6002.7 6076.4 6078.8 6101.1 6196.4 898.6 960.4 976.6 1025.7 1026.3 3989.6 4030.5 4048.6 4126.2 4148.2 10486.1 10700.9 10778.6 10809.6 10812.3 10667.1 10742.7 10774.6 10776.5 10779.4 7836.7 8255.7 8259.5 8394.6 8398.6 5059.5 5835.2 5974.9 6132.4 6241.8 9899.1 10140.7 10200.6 10202.0 10235.9 9707.5 10068.8 10086.2 10246.8 10269.8 6953.3 7181.5 7404.9 7460.5 7699.8 8369.5 8460.5 8543.2 8549.8 8559.5 7545.7 7667.9 7724.2 7806.2 7807.0 7161.4 7187.6 7404.7 7637.4 7650.3 7090.8 7150.6 7165.9 7172.7 7178.8 4912.3 5027.4 5048.8 5129.5 5144.9 5073.5 5148.2 5181.4 5212.0 5250.7 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4434.42 Median:4889.33 stddev:1357.21 (27.8%) stddev-sans-first:1325.16 11:37:11 INFO - PROCESS | 1674 | Values: 305.1 308.9 318.6 323.3 323.3 4891.9 5190.4 5388.6 5437.3 5479.8 4237.7 5158.2 5368.9 5383.3 5420.7 4121.3 4130.8 4151.8 4165.7 4192.5 3329.8 3826.7 4050.6 4174.3 4182.2 3414.3 4114.1 4118.2 4122.4 4136.5 3592.9 3908.9 4079.9 4110.0 4122.8 3528.4 3573.9 3582.5 3600.1 3632.8 5119.4 5144.9 5169.6 5282.6 5291.6 4295.7 4459.8 4685.7 4706.4 4722.2 5500.5 5565.1 5617.7 5619.7 5640.4 3243.3 3535.3 3938.4 3950.1 3992.8 4895.5 5298.5 5676.8 5819.4 5949.0 4033.2 4047.6 4054.1 4068.9 4072.7 3444.9 3740.6 3991.7 4037.9 4097.8 4634.5 4647.2 4671.5 4672.1 4678.3 5021.8 5274.4 5737.6 5750.7 5806.2 1114.8 1139.7 1272.7 1299.0 1364.8 802.3 821.3 899.6 918.9 923.5 4920.7 5018.0 5194.2 5195.0 5204.7 4799.8 5496.7 5716.3 5741.3 5757.3 4921.5 5217.6 5219.9 5263.6 5264.8 2080.9 2479.4 2519.9 2523.5 2537.3 4374.2 4879.4 4886.8 5009.9 5089.3 4319.8 4527.6 4649.8 4767.7 4926.8 4135.5 4427.7 4565.7 5019.3 5034.5 4227.4 4620.8 4809.8 4815.5 4827.9 4104.0 4726.0 4773.0 4903.0 5211.1 5117.9 5203.5 5493.6 6108.1 6177.9 3462.3 3828.6 3936.5 4002.4 4004.7 5037.9 5169.3 5897.4 5968.8 6026.5 4774.5 5231.5 5714.4 5722.4 5751.1 5685.7 5720.9 5721.2 5738.1 5740.3 5102.3 5219.6 5396.8 5607.6 5651.1 5638.2 5730.5 5736.7 5739.6 5742.6 5055.3 5248.6 5535.2 5580.4 5611.9 11:37:11 INFO - PROCESS | 1674 | 11:37:11 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:26120.45 Median:4010.73 stddev:42485.51 (1059.3%) stddev-sans-first:42561.91 11:37:11 INFO - PROCESS | 1674 | Values: 725.8 747.0 760.5 765.8 790.6 14914.1 15737.8 16342.8 16374.3 16386.8 14765.3 14963.3 14968.8 14970.3 15048.3 726.7 755.2 763.8 886.0 922.5 741.0 743.2 743.9 882.0 922.0 955.3 959.7 961.2 963.3 963.8 948.4 948.7 951.7 952.5 962.5 566.5 581.5 582.0 584.9 722.7 14682.4 15253.5 15260.5 15268.2 15286.2 12087.6 14198.9 15127.1 15161.6 15328.0 12051.9 12074.7 12113.6 12252.6 12266.0 3876.1 3957.9 3985.1 4098.2 4102.2 77712.0 98415.5 101018.0 114731.0 114938.0 11491.4 11531.6 11726.5 12135.5 12186.5 24083.9 24361.1 24374.6 24390.4 24397.8 8281.8 8339.5 8383.6 8515.7 8520.8 4288.5 4530.6 4551.4 4610.7 4823.5 3595.4 4036.3 4211.0 4213.8 4247.4 710.1 738.7 753.6 764.5 766.5 486.8 622.6 630.9 688.0 701.7 9240.9 9671.6 9689.3 9856.3 9882.1 655.8 658.5 675.2 692.8 717.1 4604.8 4768.3 4773.9 4781.5 4839.3 135128.0 156338.2 161376.0 170984.1 178555.0 144199.0 147956.0 149779.0 151948.0 153640.0 122666.4 122773.0 123161.4 123331.0 126040.4 6622.5 9153.7 9245.8 9247.7 9395.5 68236.3 68636.0 69621.0 70541.6 70625.0 68291.0 68653.7 87515.1 90349.5 95774.0 13606.9 13757.7 14080.5 14199.6 14288.9 15334.5 15347.8 15658.5 15727.1 15940.0 14347.1 14540.9 14696.6 14848.4 15000.0 7694.7 13029.5 14039.9 14427.0 14657.3 13305.4 13612.2 13801.4 14204.9 14375.4 11172.2 11284.8 11347.0 11779.2 11798.9 11250.5 11415.7 11636.7 11640.1 11650.9 11:37:11 INFO - PROCESS | 1674 | -------- Summary: end -------- 11:37:11 INFO - PROCESS | 1674 | 11:37:12 INFO - TEST-INFO | 1674: exit 0 11:37:12 INFO - TEST-OK | dromaeo_css | took 1115882ms 11:37:12 INFO - TEST-START | kraken 11:37:12 INFO - operating with platform_type : mac_ 11:37:12 INFO - Initialising browser for kraken test... 11:37:12 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpRBemK3/profile http://localhost:49275/getInfo.html) 11:37:14 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 11:37:14 INFO - PROCESS | 1681 | colorDepth:24 11:37:14 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 11:37:14 INFO - PROCESS | 1681 | __metrics 11:37:14 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:37:14 INFO - PROCESS | 1681 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:37:15 INFO - TEST-INFO | 1681: exit 0 11:37:15 INFO - Browser initialized. 11:37:15 INFO - Running cycle 1/1 for kraken test... 11:37:15 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpRBemK3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:27 INFO - PROCESS | 1682 | RSS: Main: 244686848 11:37:27 INFO - PROCESS | 1682 | 11:37:29 INFO - PROCESS | 1682 | 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) 11:37:30 INFO - PROCESS | 1682 | RSS: Main: 294498304 11:37:30 INFO - PROCESS | 1682 | 11:37:33 INFO - PROCESS | 1682 | 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) 11:37:33 INFO - PROCESS | 1682 | RSS: Main: 304623616 11:37:33 INFO - PROCESS | 1682 | 11:37:37 INFO - PROCESS | 1682 | 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) 11:37:38 INFO - PROCESS | 1682 | RSS: Main: 389378048 11:37:38 INFO - PROCESS | 1682 | 11:37:40 INFO - PROCESS | 1682 | 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) 11:37:40 INFO - PROCESS | 1682 | RSS: Main: 290971648 11:37:40 INFO - PROCESS | 1682 | 11:37:42 INFO - PROCESS | 1682 | 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) 11:37:43 INFO - PROCESS | 1682 | RSS: Main: 295399424 11:37:43 INFO - PROCESS | 1682 | 11:37:46 INFO - PROCESS | 1682 | 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) 11:37:46 INFO - PROCESS | 1682 | RSS: Main: 331468800 11:37:46 INFO - PROCESS | 1682 | 11:37:49 INFO - PROCESS | 1682 | 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) 11:37:50 INFO - PROCESS | 1682 | RSS: Main: 334602240 11:37:50 INFO - PROCESS | 1682 | 11:37:53 INFO - PROCESS | 1682 | 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) 11:37:53 INFO - PROCESS | 1682 | RSS: Main: 333328384 11:37:53 INFO - PROCESS | 1682 | 11:37:55 INFO - PROCESS | 1682 | 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) 11:37:55 INFO - PROCESS | 1682 | RSS: Main: 311971840 11:37:55 INFO - PROCESS | 1682 | 11:37:57 INFO - PROCESS | 1682 | 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) 11:37:58 INFO - PROCESS | 1682 | RSS: Main: 319397888 11:37:58 INFO - PROCESS | 1682 | 11:38:00 INFO - PROCESS | 1682 | 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) 11:38:01 INFO - PROCESS | 1682 | RSS: Main: 316628992 11:38:01 INFO - PROCESS | 1682 | 11:38:04 INFO - PROCESS | 1682 | 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) 11:38:04 INFO - PROCESS | 1682 | RSS: Main: 325353472 11:38:04 INFO - PROCESS | 1682 | 11:38:07 INFO - PROCESS | 1682 | 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) 11:38:08 INFO - PROCESS | 1682 | RSS: Main: 326795264 11:38:08 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | 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) 11:38:10 INFO - PROCESS | 1682 | RSS: Main: 370569216 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | __start_tp_report 11:38:10 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 11:38:10 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 11:38:10 INFO - PROCESS | 1682 | |i|pagename|runs| 11:38:10 INFO - PROCESS | 1682 | |0;ai-astar;101;112;113;113;115;112;114;113;113;112 11:38:10 INFO - PROCESS | 1682 | |1;audio-beat-detection;140;107;106;168;105;104;127;108;138;107 11:38:10 INFO - PROCESS | 1682 | |2;audio-dft;186;184;186;182;183;183;182;185;183;184 11:38:10 INFO - PROCESS | 1682 | |3;audio-fft;72;76;58;109;47;66;77;85;105;57 11:38:10 INFO - PROCESS | 1682 | |4;audio-oscillator;85;87;92;71;70;68;95;70;68;96 11:38:10 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;88;85;88;85;88;84;88;85;87;84 11:38:10 INFO - PROCESS | 1682 | |6;imaging-darkroom;93;93;94;93;93;92;93;93;92;105 11:38:10 INFO - PROCESS | 1682 | |7;imaging-desaturate;86;86;101;133;84;85;85;86;85;83 11:38:10 INFO - PROCESS | 1682 | |8;json-parse-financial;54;52;51;54;56;53;55;54;53;53 11:38:10 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;65;70;75;79;71;70;69;71;71;71 11:38:10 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;84;93;99;100;85;85;84;86;86;79 11:38:10 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;149;153;147;142;125;134;134;146;166;141 11:38:10 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;160;161;160;160;219;156;184;222;201;161 11:38:10 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;66;67;69;69;86;79;64;66;66;65 11:38:10 INFO - PROCESS | 1682 | __end_tp_report 11:38:10 INFO - PROCESS | 1682 | __start_cc_report 11:38:10 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2090 11:38:10 INFO - PROCESS | 1682 | __end_cc_report 11:38:10 INFO - PROCESS | 1682 | __startTimestamp1467657490477__endTimestamp 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | ------- Summary: start ------- 11:38:10 INFO - PROCESS | 1682 | Number of tests: 14 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.91 (3.5%) stddev-sans-first:1.00 11:38:10 INFO - PROCESS | 1682 | Values: 101.0 112.0 113.0 113.0 115.0 112.0 114.0 113.0 113.0 112.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:117.50 stddev:21.67 (18.4%) stddev-sans-first:21.87 11:38:10 INFO - PROCESS | 1682 | Values: 140.0 107.0 106.0 168.0 105.0 104.0 127.0 108.0 138.0 107.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.48 (0.8%) stddev-sans-first:1.33 11:38:10 INFO - PROCESS | 1682 | Values: 186.0 184.0 186.0 182.0 183.0 183.0 182.0 185.0 183.0 184.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:75.20 Median:69.00 stddev:20.13 (29.2%) stddev-sans-first:21.32 11:38:10 INFO - PROCESS | 1682 | Values: 72.0 76.0 58.0 109.0 47.0 66.0 77.0 85.0 105.0 57.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:86.00 stddev:11.87 (13.8%) stddev-sans-first:12.46 11:38:10 INFO - PROCESS | 1682 | Values: 85.0 87.0 92.0 71.0 70.0 68.0 95.0 70.0 68.0 96.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.50 stddev:1.75 (2.0%) stddev-sans-first:1.73 11:38:10 INFO - PROCESS | 1682 | Values: 88.0 85.0 88.0 85.0 88.0 84.0 88.0 85.0 87.0 84.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.00 stddev:3.87 (4.2%) stddev-sans-first:4.09 11:38:10 INFO - PROCESS | 1682 | Values: 93.0 93.0 94.0 93.0 93.0 92.0 93.0 93.0 92.0 105.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:85.00 stddev:15.49 (18.2%) stddev-sans-first:16.30 11:38:10 INFO - PROCESS | 1682 | Values: 86.0 86.0 101.0 133.0 84.0 85.0 85.0 86.0 85.0 83.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.43 (2.7%) stddev-sans-first:1.51 11:38:10 INFO - PROCESS | 1682 | Values: 54.0 52.0 51.0 54.0 56.0 53.0 55.0 54.0 53.0 53.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:3.68 (5.2%) stddev-sans-first:3.14 11:38:10 INFO - PROCESS | 1682 | Values: 65.0 70.0 75.0 79.0 71.0 70.0 69.0 71.0 71.0 71.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:6.90 (8.1%) stddev-sans-first:7.16 11:38:10 INFO - PROCESS | 1682 | Values: 84.0 93.0 99.0 100.0 85.0 85.0 84.0 86.0 86.0 79.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:146.50 stddev:11.43 (7.8%) stddev-sans-first:11.96 11:38:10 INFO - PROCESS | 1682 | Values: 149.0 153.0 147.0 142.0 125.0 134.0 134.0 146.0 166.0 141.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:172.50 stddev:26.28 (15.2%) stddev-sans-first:27.01 11:38:10 INFO - PROCESS | 1682 | Values: 160.0 161.0 160.0 160.0 219.0 156.0 184.0 222.0 201.0 161.0 11:38:10 INFO - PROCESS | 1682 | 11:38:10 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.00 stddev:7.12 (10.5%) stddev-sans-first:7.42 11:38:10 INFO - PROCESS | 1682 | Values: 66.0 67.0 69.0 69.0 86.0 79.0 64.0 66.0 66.0 65.0 11:38:10 INFO - PROCESS | 1682 | -------- Summary: end -------- 11:38:10 INFO - PROCESS | 1682 | 11:38:11 INFO - TEST-INFO | 1682: exit 0 11:38:11 INFO - TEST-OK | kraken | took 59172ms 11:38:11 INFO - SUITE-END | took 1175s 11:38:11 INFO - Completed test suite (00:19:35) 11:38:11 INFO - javascript benchmark 11:38:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3180.1890517917927, 3374.780639258448, 3394.8132852693093, 3401.6733473945164, 3402.4726167444046, 5658.43415658434, 5692.37383887772, 5798.594098413113, 5799.507041901438, 5860.413958604137, 5735.684537350444, 5796.391378905218, 5886.411358864111, 5891.204687367204, 5906.143609176666, 5131.563817075553, 5200.375954885419, 5254.842354729345, 5267.341582302212, 5394.568434525228, 5345.33183352081, 5368.677879327233, 5394.05604019297, 5397.46025397461, 5419.430959749228, 5187.896242075156, 5220.060389129955, 5256.816011439581, 5271.341082364705, 5290.365156181244, 4741.857744267678, 5073.416557095908, 5250.107481728115, 5271.973640131775, 5279.9208011879855, 5268.0, 5269.420363759983, 5309.5752339812725, 5315.963387139509, 5345.759440825133, 4852.344933433977, 5661.377248502661, 5688.544916406719, 5835.328937172237, 5903.911441328383, 6196.101565273055, 6267.5299352548745, 6289.842753931142, 6297.559170858041, 6322.241331040259, 5047.823326183603, 5802.071668533056, 6123.632582045091, 6382.297947225801, 6395.52033597482, 5729.713514324267, 5787.334456537552, 5895.292564892197, 5925.229720136398, 5939.168516407663, 6306.873862522815, 6359.904601430982, 6367.4906007519285, 6384.0, 6393.648349340738, 6262.12330273762, 6265.937340626627, 6274.215723034621, 6287.0, 6294.244690637152, 6198.659073750987, 6229.719662615194, 6306.463950564162, 6354.078658594478, 6388.2653494847755, 6273.059041143773, 6306.621602703853, 6355.777547785805, 6361.777337793247, 6369.108324834527, 6380.330065343072, 6386.0, 6386.201724784402, 6387.169667943138, 6400.0719895614875, 4884.291777692215, 4936.531029552187, 4970.15507363749, 4977.676451030671, 5032.144535428986, 4436.800343984464, 4447.355133505624, 4463.732176069446, 4486.192485352668, 4504.009117994036, 5797.0, 5816.534677225812, 5822.184894114827, 5826.359100498942, 5881.9705901470215, 6253.5622506424115, 6281.277653069956, 6287.402696743802, 6351.777687780998, 6354.4281014708895, 5795.304563452412, 5842.970785146132, 5846.941530584725, 5850.297964244232, 5858.150568167592, 5520.3651580068, 5521.972390138024, 5522.475364840334, 5526.47498487643, 5533.695646739468, 6276.309605943343, 6311.999999999908, 6343.460805831556, 6369.968150159405, 6440.001799721051, 6354.872902542016, 6376.617402955838, 6387.489000880011, 6388.8083357499345, 6411.326810684997, 6458.61248325102, 6460.192475940507, 6464.030395440722, 6468.708908099053, 6590.373914478117, 6134.04922237054, 6197.752089916353, 6218.471429928507, 6262.029385445263, 6326.9683651581445, 6338.302786693553, 6374.0, 6391.169148010915, 6401.775937842153, 6413.230412350361, 6028.728707208274, 6130.141780150871, 6212.627242365473, 6237.875242495215, 6244.344343843831, 6122.520135281246, 6125.173101631222, 6153.815385538441, 6228.968855155877, 6239.126522286974, 6032.909506357496, 6035.728392222272, 6040.054891509743, 6066.4540191382985, 6086.513078953762, 6040.6677632730625, 6089.025755879038, 6165.630062196283, 6197.659128747962, 6222.906656400248, 5718.228039214653, 5860.794872179454, 5976.760929562769, 5985.760569577168, 6092.908606370819], "value": 5768.327367477698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.3716168980326, 488.68968205189697, 502.55272807201595, 502.9597632189425, 505.6511007404888, 29751.55372669409, 41902.16195676089, 42504.93737656556, 42686.57313426865, 44966.32550511745, 41989.58010419895, 42866.7856660717, 45194.774026129824, 45377.54622453774, 45476.63570092894, 8731.95634021829, 9673.61305547779, 9829.164521015722, 9848.753781155492, 9863.852042219372, 8742.606582703762, 11821.0, 12004.339761313124, 12021.278723276588, 12328.0, 11983.400829958468, 12048.879511204863, 12056.758864822697, 12060.457279422448, 12089.939550302237, 7798.025246844088, 8781.912180878173, 8969.0, 11909.344986025764, 12086.093542984267, 8840.027596964328, 11452.484638191301, 11718.296902185895, 11727.824082638766, 11985.520579176822, 42643.57356426427, 43004.0, 43079.7846010768, 43193.0, 43353.566464335265, 3528.5942116656656, 3596.7662101963288, 3876.8449262029485, 4219.578042195786, 4242.639375653073, 32568.348633027443, 33233.6676633233, 33658.831705841316, 34074.318513629834, 34256.82871585651, 3241.837908104609, 3296.620888597794, 3296.6373698893103, 3404.540387047766, 3411.385950528904, 534184.0, 536548.0, 538040.0, 538291.0, 545023.0, 65220.673896630215, 65555.01667474992, 65619.67190164019, 65731.38477383694, 65823.0, 68762.65618671874, 69472.95790563145, 69520.0, 69572.65213674, 69827.65086174537, 16702.164891755365, 17088.57278568046, 17134.914325428294, 17165.141742912805, 17174.96950182968, 11105.333679979227, 11800.937915587638, 12062.638120856389, 12076.456559454846, 12239.265644061385, 14937.0, 16327.510174694778, 17886.0, 18240.45278641643, 18313.450596482126, 6464.256610489854, 7506.662200201004, 7559.508631938907, 7640.471905618899, 7738.92261077382, 901.9233365164034, 957.3537861943161, 989.0752146742818, 996.4071377530358, 1023.2019025160387, 27862.0, 28841.85579072091, 30659.0, 31383.0, 32233.0, 959.8608201810698, 1079.7462596289865, 1130.6890605083668, 1134.8127558952845, 1150.9597164099216, 15330.658819650289, 16189.0, 16462.75305870413, 16647.750283745754, 16883.662326753398, 553972.460275392, 588472.0, 589944.0, 591021.0, 592713.4371937697, 61739.0, 62403.0, 62490.0, 62595.748085038955, 62634.0, 1286.8327117474669, 1342.0940864916338, 1435.8923080768984, 1440.9567712968628, 1452.3682198243798, 25156.49687006213, 30734.53898191484, 33289.334213316084, 34068.488972664934, 34185.145371365914, 61227.77544449175, 61845.0, 61877.0, 62145.689271553354, 62179.689101554206, 623358.0, 644471.0, 649060.0, 649137.0, 649959.0, 36740.44889326609, 37423.25153496969, 41103.58896410998, 43421.78289108534, 44075.77962110169, 51137.744311278206, 51147.885211478555, 51252.74373628108, 52078.21882671839, 52185.95628087493, 33014.66985330212, 40494.59505405026, 42254.0, 42307.36538951856, 43411.34882976695, 41348.793256033525, 41672.583274168086, 41678.79160604178, 42755.0, 42780.0, 40118.99702507344, 40172.799136004134, 41427.79286103671, 41524.96187595334, 43481.78259108684, 34026.659733403336, 36608.0, 37112.81443592765, 37118.0, 37379.0, 22108.2262120825, 26227.0, 26355.736442636095, 26583.734162658126, 26627.733722662524], "value": 21321.637779408506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.97157713171515, 381.91025109099377, 386.5921452867226, 399.4467662287731, 400.46137944464704, 19163.329283475083, 20978.160873565077, 21245.362639120813, 21372.57254854904, 21376.144954201834, 10338.0, 11881.7623647527, 12385.938070309636, 12390.93804530976, 20447.591048179063, 5310.787568497255, 5493.285872836525, 5551.306086739158, 5556.222128901957, 5558.027345214591, 3964.7224694271413, 4096.692748043909, 4419.138268037734, 6789.490788190881, 6813.250542440328, 6781.287964763703, 6786.049953006583, 6840.213375461811, 6842.384185423311, 6846.4865135114815, 6824.658767061652, 6825.726970921157, 6827.829304267383, 6829.556078854858, 6834.726610935581, 3845.3462911305146, 4071.226466971266, 5628.380878103405, 5950.702464876782, 5954.612950158225, 13368.264745438997, 13383.799243011363, 13687.178769273776, 13689.726205475934, 13775.38010789517, 2169.2971017751147, 2418.8147807574337, 2436.6832311799353, 2491.5639763041577, 2998.7750918681186, 9747.658831940918, 10124.89875101247, 10430.478476076241, 10434.9478252609, 10685.35887846724, 1700.1159397113431, 1852.2868695552174, 1887.811218878115, 1894.668433024229, 1906.780720217179, 90740.6388904167, 95396.52301738448, 122312.0, 127968.0, 135742.999999999, 9936.652217172365, 10137.493125343703, 11395.772084558263, 11556.537738490535, 11905.880941190651, 12694.49222031127, 13641.454341826216, 14740.41038358475, 14839.10965342083, 14875.851241487446, 8636.740897773077, 8784.60469278884, 8847.42491738035, 8887.688930887389, 8922.464652120894, 8617.913820861711, 8823.602937867812, 8827.602757875911, 8978.955105224562, 9051.728448146567, 3755.8497660093835, 4604.1712491751805, 4631.861044168681, 4634.397528321297, 4699.694519856198, 1627.894186877849, 1660.4022551881312, 1935.6225536020622, 1950.590376021023, 1951.9121639526256, 810.8769354444138, 925.3707478914268, 935.4675853057324, 945.5933948402115, 997.7006897930592, 21124.0, 21702.674459883114, 21773.45566360822, 21790.455238618844, 21966.670499942513, 924.7965447601517, 952.3333666433385, 998.5706146357136, 1003.5734812704571, 1021.4994652620163, 11343.659690209306, 11412.144089193178, 11430.028447582048, 11563.30620162793, 11665.708357291136, 593789.0, 604833.0, 617412.0, 624472.0, 625605.0, 126989.82525436938, 129566.0, 130745.0, 133650.0, 135294.32352838173, 964.4791812421354, 1011.5953618552638, 1012.7468132966758, 1083.128081894067, 1083.8320060390652, 18102.637947241245, 18966.24135034583, 19239.90380048147, 19304.32434864773, 19370.934598597214, 39384.424623015904, 50483.495165047876, 54286.0, 56528.434715652314, 56618.43381566297, 76789.61605191938, 79018.0, 79996.0, 81179.6938275558, 81634.18365816266, 7972.162922893012, 8178.550179740171, 8342.249197572248, 8355.707550235713, 8471.32229421657, 9333.393329433566, 10962.671119866416, 13245.27151006704, 13364.264965426995, 13366.398512067175, 11176.776464470828, 11476.827847582126, 11574.016208622024, 11584.652460426201, 11605.825912611148, 8951.373403861668, 10955.233133680566, 11152.275102118336, 11184.827503641518, 11331.716707082389, 11672.066234701373, 11686.532538698697, 11793.587224447103, 11881.702957426134, 11902.40487975615, 6961.582305061713, 8239.546824924686, 8270.83458330842, 8455.95772021136, 8465.06884242745, 10494.895051049392, 11485.655430337103, 11643.0, 11645.184837061324, 11667.533298667959], "value": 9395.53808286524, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.845686974432, 1561.125769569041, 1592.5063230398573, 1807.4939017075214, 1949.3469687654626, 7912.723054693087, 8706.085860984593, 8965.10348965104, 8969.237614802732, 9098.681546145886, 8760.123987601237, 8955.0, 8961.32790040748, 8961.924569051707, 8981.910180898189, 2120.5016821047043, 2189.419803751999, 2325.104834638668, 2374.6319320505304, 2408.759124087595, 2387.617981123021, 2430.4895971845935, 2433.124075332879, 2433.4159801647565, 2440.6095024796036, 2358.363241924679, 2388.7372389037278, 2405.7113146422366, 2405.8797060147012, 2408.217329367954, 2102.148629804924, 2305.8501197422115, 2369.9052037918464, 2371.905123795046, 2378.2389635316704, 2357.351728274738, 2380.345405013618, 2383.487550176703, 2385.093664407514, 2391.9521609567887, 8942.239909607692, 8992.730218093468, 9020.594073266653, 9028.50343231122, 9031.096890310982, 5259.973700131475, 5268.367795864521, 5418.512333889968, 5421.403645598981, 5432.972835135839, 3578.588462326814, 3626.3291291111227, 3684.44733290006, 3684.9815750921343, 3697.6856967157823, 1043.7286305560533, 1076.0960792933895, 1100.5322737836389, 1113.7271368514685, 1114.8773634900153, 11889.097744360948, 11891.464884080237, 11899.524019039229, 11904.16670833042, 11918.046556275478, 4608.28571571407, 4616.422947131608, 4853.223484242575, 4854.83008094715, 4865.781039853215, 10012.849807252896, 10018.248631352531, 10020.448875311782, 10024.097831195082, 10033.197344212445, 5412.756425960842, 5419.0, 5427.3215848019, 5427.3215848019, 5427.48438898304, 4447.132809102259, 4599.264117741145, 4799.352087468217, 4909.607231421478, 4917.9016419672125, 3937.448757173998, 4006.2988976928923, 4058.898527536835, 4085.3871919212356, 4123.670106391482, 1196.2642974570576, 1296.2028352563295, 1302.9022823288292, 1304.4456106155035, 1315.0268801087318, 816.3387655998661, 939.5865819039601, 947.3179310896144, 962.5091203486272, 972.3922548407245, 6002.669853158118, 6076.422739839708, 6078.787242446493, 6101.054336577838, 6196.43316640606, 898.6360523987795, 960.4333443268505, 976.5947131940308, 1025.7179275699093, 1026.3174988632625, 3989.5810939851476, 4030.5163380394542, 4048.554658987568, 4126.154138401621, 4148.211839750498, 10486.056254937092, 10700.89299106999, 10778.568857245778, 10809.621663241749, 10812.297200681931, 10667.093297069681, 10742.677719668422, 10774.569017239379, 10776.515056822462, 10779.407132607781, 7836.725714599962, 8255.711050113217, 8259.545724985064, 8394.58027098655, 8398.580070996426, 5059.494050594911, 5835.212246346859, 5974.880502390015, 6132.41742034506, 6241.843953901189, 9899.109080182818, 10140.695779126638, 10200.591976320864, 10202.030807073315, 10235.897641023494, 9707.514624268617, 10068.798624027331, 10086.243531735003, 10246.846297305696, 10269.84595231087, 6953.269906659728, 7181.533200341961, 7404.888926665996, 7460.515066520658, 7699.8075048122, 8369.49783013021, 8460.534670593177, 8543.231109199953, 8549.829003419773, 8559.486430813922, 7545.6981720730555, 7667.923320766721, 7724.227577242231, 7806.1803510632835, 7806.960965195138, 7161.355478007004, 7187.640617969185, 7404.740834070782, 7637.350825179922, 7650.273224043707, 7090.822729431806, 7150.606716630636, 7165.928340716526, 7172.713091476491, 7178.820529486805, 4912.312276281395, 5027.4218464877, 5048.823291184791, 5129.538341549279, 5144.871378215425, 5073.531966427883, 5148.150555158357, 5181.35233095863, 5211.973940130275, 5250.65870718402], "value": 4661.065872936758, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.1395065914123, 308.8841684368362, 318.6240236520905, 323.3339320998744, 323.33877221082884, 4891.850415152437, 5190.351206099238, 5388.568914486841, 5437.308532276524, 5479.808206712766, 4237.682173836959, 5158.200478925773, 5368.892622147565, 5383.273258110154, 5420.728963551826, 4121.340585506319, 4130.834766609347, 4151.813168407414, 4165.666746660268, 4192.49690037196, 3329.816860072695, 3826.65560099591, 4050.5949405059405, 4174.332106862903, 4182.16356728654, 3414.265932824442, 4114.128071727372, 4118.238125946709, 4122.4228607994755, 4136.544980052192, 3592.8562857485667, 3908.9413658795143, 4079.8572049978106, 4109.958900410988, 4122.773247471388, 3528.3648943190215, 3573.857045718168, 3582.498450216972, 3600.117971097098, 3632.7820330780237, 5119.385673719177, 5144.871378215537, 5169.612279079084, 5282.630215884889, 5291.576673866081, 4295.720778149442, 4459.773562270369, 4685.672002959795, 4706.411698537683, 4722.244440889475, 5500.487365974389, 5565.1095824667855, 5617.662940223599, 5619.690916999563, 5640.407757185519, 3243.3189030303665, 3535.3106144301873, 3938.369860822255, 3950.071733142709, 3992.7803970781597, 4895.461499235082, 5298.516415403693, 5676.801311954103, 5819.359870414251, 5949.0, 4033.153037862082, 4047.5750046245307, 4054.0675644601906, 4068.8575899843954, 4072.714909956334, 3444.8794292199655, 3740.588535261119, 3991.6806655467494, 4037.858674946363, 4097.815598298083, 4634.516954574504, 4647.233206520887, 4671.48613652498, 4672.112298663243, 4678.274867395559, 5021.774020169101, 5274.446183150791, 5737.5983681141915, 5750.654960702372, 5806.2451881255165, 1114.8494953181216, 1139.6865861888048, 1272.6500212441467, 1298.9870101299055, 1364.8089267502558, 802.2579114319232, 821.2526600793369, 899.5682072605186, 918.8621706744042, 923.5105394140982, 4920.696015555881, 5017.974910125499, 5194.22086687, 5194.97402512985, 5204.713740744295, 4799.784009719571, 5496.697681627549, 5716.314042314949, 5741.282339707536, 5757.280339957505, 4921.458639549646, 5217.634765566374, 5219.947800522022, 5263.6315457918345, 5264.814787053854, 2080.937571872846, 2479.429731161807, 2519.9164359325664, 2523.4953009398196, 2537.314924970248, 4374.162851602335, 4879.4385796545475, 4886.804527818918, 5009.924851127236, 5089.262057001788, 4319.784010799447, 4527.569880861313, 4649.814007439664, 4767.69010014348, 4926.827561035347, 4135.503739551213, 4427.690061695715, 4565.703229290219, 5019.297298378303, 5034.496550345009, 4227.408162857264, 4620.798592365942, 4809.831655892167, 4815.542523460265, 4827.927581086356, 4104.0, 4725.983913458623, 4772.969164580586, 4902.950970490249, 5211.140161873249, 5117.923231151462, 5203.479652034917, 5493.560515158858, 6108.144859719554, 6177.938220617736, 3462.2902305027915, 3828.559715632738, 3936.468576742218, 4002.3996400538986, 4004.719669623215, 5037.92443113361, 5169.276301317744, 5897.3807750185615, 5968.820935371772, 6026.548008899263, 4774.466894498116, 5231.476852314738, 5714.3999280453045, 5722.370539240597, 5751.079827227623, 5685.71571421419, 5720.8569785754025, 5721.22763426932, 5738.102258359501, 5740.339860915881, 5102.300831896845, 5219.556337711185, 5396.811111611232, 5607.635503692247, 5651.050387616266, 5638.23883775685, 5730.455606717355, 5736.6558006519745, 5739.5695322850115, 5742.569307302053, 5055.292259083658, 5248.60635452335, 5535.225068490334, 5580.441955804387, 5611.915821262603], "value": 4002.2230122369906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.8439435521364, 746.9514481558698, 760.5132715062358, 765.7664412354231, 790.6363072986426, 14914.105153690785, 15737.84262157378, 16342.83657163428, 16374.345026198953, 16386.83613163868, 14765.335559899804, 14963.326650300736, 14968.775468367983, 14970.32633531491, 15048.322825472853, 726.6838925067586, 755.161770434817, 763.7975936376864, 885.9689910853122, 922.5110691333604, 741.0144507804633, 743.2418932688654, 743.8884167374883, 882.0297672560197, 921.9720012186398, 955.2692190474293, 959.6593209410663, 961.1974001708581, 963.3401120232663, 963.7542426681186, 948.3598570964572, 948.6869333120054, 951.728757304172, 952.5142177489496, 962.4946902876011, 566.5326105962597, 581.5318668471879, 581.953443724501, 584.9327327357366, 722.7145277615365, 14682.412703491847, 15253.542393728207, 15260.465883694129, 15268.236588170546, 15286.235688215545, 12087.637370878887, 14198.85801141996, 15127.092374457568, 15161.620959475991, 15328.0, 12051.879481205251, 12074.69813254667, 12113.576024839089, 12252.632421027383, 12266.0, 3876.127871228979, 3957.8614748483665, 3985.123272879991, 4098.221337945811, 4102.2410853991805, 77712.0, 98415.50792246065, 101018.0, 114730.99999999917, 114938.0, 11491.367974761468, 11531.596394126165, 11726.472308746126, 12135.514579416724, 12186.451609677586, 24083.879580602334, 24361.14735984232, 24374.634380484305, 24390.390240244134, 24397.756022439902, 8281.792955176048, 8339.499630022217, 8383.58082095905, 8515.701950431705, 8520.829583408296, 4288.52826189119, 4530.61489773373, 4551.385562949025, 4610.654200934943, 4823.517648235219, 3595.406757884972, 4036.334004889219, 4210.957890421118, 4213.831446742157, 4247.4265974093105, 710.0910834132319, 738.7488253993561, 753.5516367761173, 764.4992529892929, 766.4596459496042, 486.7882471125072, 622.5735371270694, 630.854903372227, 687.9508749157475, 701.7017767448814, 9240.89119274278, 9671.564779584794, 9689.27330450205, 9856.310058295994, 9882.061204185591, 655.799791698853, 658.5357323087212, 675.1965161457849, 692.8094773937207, 717.1215261304964, 4604.769761511978, 4768.260919557474, 4773.904521909542, 4781.474037855833, 4839.322494850724, 135128.0, 156338.21830891, 161376.0, 170984.1450792763, 178555.0, 144199.0, 147956.0, 149779.0, 151948.0, 153640.0, 122666.38666806788, 122773.0, 123161.38419307845, 123331.0, 126040.36979815042, 6622.503312251504, 9153.725388238363, 9245.815083698422, 9247.657482372177, 9395.460497599226, 68236.31763682299, 68636.0, 69621.0, 70541.6472917632, 70624.99070175667, 68290.95127146193, 68653.65673171602, 87515.1248487507, 90349.54825226095, 95774.0, 13606.86393136056, 13757.656058598615, 14080.471166812122, 14199.64500887486, 14288.928555357572, 15334.539963801104, 15347.846521535088, 15658.530244092695, 15727.135007574694, 15940.043597384194, 14347.067440616327, 14540.85459145395, 14696.55910322692, 14848.40606375733, 15000.0, 7694.653740581575, 13029.543965961526, 14039.929800350932, 14427.0, 14657.267136643339, 13305.401256943267, 13612.183269003892, 13801.447942082204, 14204.928975355471, 14375.353109110325, 11172.183056518115, 11284.774304514029, 11347.0, 11779.234349767237, 11798.941005294919, 11250.455741847944, 11415.714607134889, 11636.650900473, 11640.126990475917, 11650.88349116498], "value": 8103.903493999825, "unit": "score"}], "name": "dromaeo_css", "value": 7476.877356470794, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 113.0, 113.0, 115.0, 112.0, 114.0, 113.0, 113.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 106.0, 168.0, 105.0, 104.0, 127.0, 108.0, 138.0, 107.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 182.0, 183.0, 183.0, 182.0, 185.0, 183.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 58.0, 109.0, 47.0, 66.0, 77.0, 85.0, 105.0, 57.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 92.0, 71.0, 70.0, 68.0, 95.0, 70.0, 68.0, 96.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 105.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 101.0, 133.0, 84.0, 85.0, 85.0, 86.0, 85.0, 83.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 54.0, 56.0, 53.0, 55.0, 54.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 75.0, 79.0, 71.0, 70.0, 69.0, 71.0, 71.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 99.0, 100.0, 85.0, 85.0, 84.0, 86.0, 86.0, 79.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 153.0, 147.0, 142.0, 125.0, 134.0, 134.0, 146.0, 166.0, 141.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 219.0, 156.0, 184.0, 222.0, 201.0, 161.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 69.0, 86.0, 79.0, 64.0, 66.0, 66.0, 65.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1448.3000000000002, "alertThreshold": 2.0}]} 11:38:11 INFO - Return code: 0 11:38:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:38:11 INFO - # TBPL SUCCESS # 11:38:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:38:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:38:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:38:11 INFO - 2016-07-04 11:38:11.784 screenresolution[1686:9875] starting screenresolution argv=screenresolution get 11:38:11 INFO - 2016-07-04 11:38:11.787 screenresolution[1686:9875] Display 0: 1600x1200x32@60 11:38:11 INFO - 2016-07-04 11:38:11.793 screenresolution[1687:9878] starting screenresolution argv=screenresolution list 11:38:11 INFO - Available Modes on Display 0 11:38:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:38:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:38:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:38:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:38:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:38:12 INFO - Intel Iris: 11:38:12 INFO - Chipset Model: Intel Iris 11:38:12 INFO - Type: GPU 11:38:12 INFO - Bus: Built-In 11:38:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:38:12 INFO - Vendor: Intel (0x8086) 11:38:12 INFO - Device ID: 0x0a2e 11:38:12 INFO - Revision ID: 0x0009 11:38:12 INFO - Displays: 11:38:12 INFO - Display: 11:38:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:38:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:38:12 INFO - Display Serial Number: 1600x1200 60 11:38:12 INFO - Main Display: Yes 11:38:12 INFO - Mirror: Off 11:38:12 INFO - Online: Yes 11:38:12 INFO - Rotation: Supported 11:38:12 INFO - Return code: 0 11:38:12 INFO - Running post-action listener: _resource_record_post_action 11:38:12 INFO - ##### 11:38:12 INFO - ##### Finished run-tests step (success) 11:38:12 INFO - ##### 11:38:12 INFO - Running post-run listener: _resource_record_post_run 11:38:12 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36877824; Write bytes: 703346176; Read time: 237; Write time: 1910 11:38:12 INFO - TinderboxPrint: CPU usage
53.5% 11:38:12 INFO - TinderboxPrint: I/O read bytes / time
36,877,824 / 237 11:38:12 INFO - TinderboxPrint: I/O write bytes / time
703,346,176 / 1,910 11:38:12 INFO - TinderboxPrint: CPU idle
2,249.6 (46.5%) 11:38:12 INFO - TinderboxPrint: CPU system
966.1 (20.0%) 11:38:12 INFO - TinderboxPrint: CPU user
1,626.0 (33.6%) 11:38:12 INFO - TinderboxPrint: Swap in / out
443,105,280 / 0 11:38:12 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398553600; Write bytes: 391743488; Read time: 29839; Write time: 790 11:38:12 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34878976; Write bytes: 293780992; Read time: 219; Write time: 1073 11:38:12 INFO - Running post-run listener: _upload_blobber_files 11:38:12 INFO - Blob upload gear active. 11:38:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:38:12 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 11:38:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:38:12 INFO - (blobuploader) - INFO - Open directory for files ... 11:38:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:38:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:38:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:13 INFO - (blobuploader) - INFO - Done attempting. 11:38:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:38:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:14 INFO - (blobuploader) - INFO - Done attempting. 11:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:38:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:17 INFO - (blobuploader) - INFO - Done attempting. 11:38:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:38:17 INFO - Return code: 0 11:38:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:38:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:38:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f32463b0ea14c641a3ed215461ccf1607a66831dcfa2dcf55076d26d4cbc32990726c874bdc32da1775bcfeddf6fed2eda937595d5320899009e67f5d4be75a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d2b0bce08c2d210b12003130f8bbffa50dd1ce90a2a4ef4e31b51cb1ca7e1fe9e6b237ac78daa2421508b1c8bfc72f5dc4ba1c750fd3317543fa30a31a014b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b30473eb0748edbcd5959ee8f33fbc8ac4b43e58bf6f9db9d4884fb4a8887cd76b202497894770750fa8696651e54f71659bb288d59a1ddd3f65de6505e61d"} 11:38:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:38:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:38:17 INFO - Contents: 11:38:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f32463b0ea14c641a3ed215461ccf1607a66831dcfa2dcf55076d26d4cbc32990726c874bdc32da1775bcfeddf6fed2eda937595d5320899009e67f5d4be75a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d2b0bce08c2d210b12003130f8bbffa50dd1ce90a2a4ef4e31b51cb1ca7e1fe9e6b237ac78daa2421508b1c8bfc72f5dc4ba1c750fd3317543fa30a31a014b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b30473eb0748edbcd5959ee8f33fbc8ac4b43e58bf6f9db9d4884fb4a8887cd76b202497894770750fa8696651e54f71659bb288d59a1ddd3f65de6505e61d"} 11:38:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:38:17 INFO - Copying logs to upload dir... 11:38:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:38:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.554034 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-04 11:38:17.229490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 11:38:17.233540) ========= 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.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f32463b0ea14c641a3ed215461ccf1607a66831dcfa2dcf55076d26d4cbc32990726c874bdc32da1775bcfeddf6fed2eda937595d5320899009e67f5d4be75a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d2b0bce08c2d210b12003130f8bbffa50dd1ce90a2a4ef4e31b51cb1ca7e1fe9e6b237ac78daa2421508b1c8bfc72f5dc4ba1c750fd3317543fa30a31a014b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b30473eb0748edbcd5959ee8f33fbc8ac4b43e58bf6f9db9d4884fb4a8887cd76b202497894770750fa8696651e54f71659bb288d59a1ddd3f65de6505e61d"} build_url:https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/YssiKjBxQV2vHBT8YDksgw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f32463b0ea14c641a3ed215461ccf1607a66831dcfa2dcf55076d26d4cbc32990726c874bdc32da1775bcfeddf6fed2eda937595d5320899009e67f5d4be75a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d2b0bce08c2d210b12003130f8bbffa50dd1ce90a2a4ef4e31b51cb1ca7e1fe9e6b237ac78daa2421508b1c8bfc72f5dc4ba1c750fd3317543fa30a31a014b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b30473eb0748edbcd5959ee8f33fbc8ac4b43e58bf6f9db9d4884fb4a8887cd76b202497894770750fa8696651e54f71659bb288d59a1ddd3f65de6505e61d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 11:38:17.269538) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:38:17.269910) ========= 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.EzkcRCuYd5/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.pNtcoO55pB/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.005118 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 11:38:17.319300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 11:38:17.319652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 11:38:17.320053) ========= ========= Total master_lag: 0.95 =========