builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0211 starttime: 1469595360.34 results: success (0) buildid: 20160704090257 builduid: d669e6d68d0f44ceb7eae135dfd4697d revision: f378a56b25ce2a2997b263c1857629f3f18d7400 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:00.338811) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:00.339312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:00.339714) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:00.373004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:00.373708) ========= 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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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-26 21:56:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 57.6M=0s 2016-07-26 21:56:00 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.468861 ========= master_lag: 0.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:01.326576) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:01.326959) ========= 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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.028817 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:01.379654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:01.379998) ========= 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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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-26 21:56:01,498 truncating revision to first 12 chars 2016-07-26 21:56:01,498 Setting DEBUG logging. 2016-07-26 21:56:01,498 attempt 1/10 2016-07-26 21:56:01,498 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-26 21:56:02,142 unpacking tar archive at: mozilla-central-f378a56b25ce/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927235 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:02.360764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:02.361077) ========= script_repo_revision: f378a56b25ce2a2997b263c1857629f3f18d7400 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:02.361487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:02.361808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 21:56:02.380931) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-26 21:56:02.381274) ========= /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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:02 INFO - MultiFileLogger online at 20160726 21:56:02 in /builds/slave/test 21:56:02 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 21:56:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:56:02 INFO - {'append_to_log': False, 21:56:02 INFO - 'base_work_dir': '/builds/slave/test', 21:56:02 INFO - 'blob_upload_branch': 'Firefox', 21:56:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:56:02 INFO - 'branch': 'Firefox', 21:56:02 INFO - 'buildbot_json_path': 'buildprops.json', 21:56:02 INFO - 'config_files': ('talos/mac_config.py',), 21:56:02 INFO - 'default_actions': ('clobber', 21:56:02 INFO - 'read-buildbot-config', 21:56:02 INFO - 'download-and-extract', 21:56:02 INFO - 'populate-webroot', 21:56:02 INFO - 'create-virtualenv', 21:56:02 INFO - 'install', 21:56:02 INFO - 'run-tests'), 21:56:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:56:02 INFO - 'download_minidump_stackwalk': True, 21:56:02 INFO - 'download_symbols': 'ondemand', 21:56:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:56:02 INFO - 'tooltool.py': '/tools/tooltool.py', 21:56:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:56:02 INFO - '/tools/misc-python/virtualenv.py')}, 21:56:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:56:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:56:02 INFO - 'installer_path': 'installer.exe', 21:56:02 INFO - 'log_level': 'info', 21:56:02 INFO - 'log_name': 'talos', 21:56:02 INFO - 'log_to_console': True, 21:56:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:56:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:56:02 INFO - 'opt_config_files': (), 21:56:02 INFO - 'pip_index': False, 21:56:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:56:02 INFO - 'cmd': ('bash', 21:56:02 INFO - '-c', 21:56:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:56:02 INFO - 'enabled': True, 21:56:02 INFO - 'halt_on_failure': False, 21:56:02 INFO - 'name': 'check_screen_resolution'},), 21:56:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:56:02 INFO - 'cmd': ('bash', 21:56:02 INFO - '-c', 21:56:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:56:02 INFO - 'enabled': True, 21:56:02 INFO - 'halt_on_failure': False, 21:56:02 INFO - 'name': 'check_screen_resolution'},), 21:56:02 INFO - 'run_cmd_checks_enabled': True, 21:56:02 INFO - 'sps_profile': False, 21:56:02 INFO - 'sps_profile_interval': 0, 21:56:02 INFO - 'suite': 'dromaeojs', 21:56:02 INFO - 'system_bits': '32', 21:56:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:56:02 INFO - 'title': 't-yosemite-r7-0211', 21:56:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:56:02 INFO - 'use_talos_json': True, 21:56:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:56:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:56:02 INFO - 'work_dir': 'build'} 21:56:02 INFO - ##### 21:56:02 INFO - ##### Running clobber step. 21:56:02 INFO - ##### 21:56:02 INFO - Running pre-action listener: _resource_record_pre_action 21:56:02 INFO - Running main action method: clobber 21:56:02 INFO - rmtree: /builds/slave/test/build 21:56:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:56:04 INFO - Running post-action listener: _resource_record_post_action 21:56:04 INFO - ##### 21:56:04 INFO - ##### Finished clobber step (success) 21:56:04 INFO - ##### 21:56:04 INFO - ##### 21:56:04 INFO - ##### Running read-buildbot-config step. 21:56:04 INFO - ##### 21:56:04 INFO - Running pre-action listener: _resource_record_pre_action 21:56:04 INFO - Running main action method: read_buildbot_config 21:56:04 INFO - Using buildbot properties: 21:56:04 INFO - { 21:56:04 INFO - "project": "", 21:56:04 INFO - "product": "firefox", 21:56:04 INFO - "script_repo_revision": "production", 21:56:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:56:04 INFO - "repository": "", 21:56:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:56:04 INFO - "buildid": "20160704090257", 21:56:04 INFO - "pgo_build": "False", 21:56:04 INFO - "basedir": "/builds/slave/test", 21:56:04 INFO - "buildnumber": 43, 21:56:04 INFO - "slavename": "t-yosemite-r7-0211", 21:56:04 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:56:04 INFO - "platform": "yosemite_r7", 21:56:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:56:04 INFO - "branch": "mozilla-central", 21:56:04 INFO - "repo_path": "mozilla-central", 21:56:04 INFO - "revision": "f378a56b25ce2a2997b263c1857629f3f18d7400", 21:56:04 INFO - "stage_platform": "macosx64", 21:56:04 INFO - "builduid": "d669e6d68d0f44ceb7eae135dfd4697d", 21:56:04 INFO - "slavebuilddir": "test" 21:56:04 INFO - } 21:56:04 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg. 21:56:04 INFO - Running post-action listener: _resource_record_post_action 21:56:04 INFO - ##### 21:56:04 INFO - ##### Finished read-buildbot-config step (success) 21:56:04 INFO - ##### 21:56:04 INFO - ##### 21:56:04 INFO - ##### Running download-and-extract step. 21:56:04 INFO - ##### 21:56:04 INFO - Running pre-action listener: _resource_record_pre_action 21:56:04 INFO - Running main action method: download_and_extract 21:56:04 INFO - mkdir: /builds/slave/test/build/tests 21:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.test_packages.json 21:56:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 21:56:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/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 21:56:05 INFO - Downloaded 1199 bytes. 21:56:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 21:56:05 INFO - Using the following test package requirements: 21:56:05 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:56:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 21:56:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 21:56:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'jsshell-mac.zip'], 21:56:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 21:56:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:56:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 21:56:05 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 21:56:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 21:56:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:56:05 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 21:56:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.common.tests.zip 21:56:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:56:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/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 21:56:06 INFO - Downloaded 18795221 bytes. 21:56:06 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'] 21:56:06 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 21:56:09 INFO - Return code: 0 21:56:09 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 21:56:09 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')]} 21:56:09 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.common.tests.zip 21:56:09 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:56:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/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 21:56:10 INFO - Downloaded 18795221 bytes. 21:56:10 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'] 21:56:10 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 21:56:13 INFO - Return code: 0 21:56:13 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')]} 21:56:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.talos.tests.zip 21:56:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 21:56:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/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 21:56:20 INFO - Downloaded 14403333 bytes. 21:56:20 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'] 21:56:20 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 21:56:20 INFO - Return code: 0 21:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg 21:56:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 21:56:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 21:56:22 INFO - Downloaded 112417056 bytes. 21:56:22 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg 21:56:22 INFO - mkdir: /builds/slave/test/properties 21:56:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:56:22 INFO - Writing to file /builds/slave/test/properties/build_url 21:56:22 INFO - Contents: 21:56:22 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg 21:56:22 INFO - Running post-action listener: _resource_record_post_action 21:56:22 INFO - Running post-action listener: set_extra_try_arguments 21:56:22 INFO - ##### 21:56:22 INFO - ##### Finished download-and-extract step (success) 21:56:22 INFO - ##### 21:56:22 INFO - ##### 21:56:22 INFO - ##### Running populate-webroot step. 21:56:22 INFO - ##### 21:56:22 INFO - Running pre-action listener: _resource_record_pre_action 21:56:22 INFO - Running main action method: populate_webroot 21:56:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:56:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:56:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:56:22 INFO - '--tppagecycles', 21:56:22 INFO - '7'], 21:56:22 INFO - 'tests': ['tsvgm']}}, 21:56:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:56:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:56:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:56:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:56:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['damp', 'tps']}, 21:56:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'tests': ['damp', 'tps']}, 21:56:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['dromaeo_dom']}, 21:56:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:56:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['basic_compositor_video']}, 21:56:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:56:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'other-e10s': {'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['a11yr', 21:56:22 INFO - 'ts_paint', 21:56:22 INFO - 'tpaint', 21:56:22 INFO - 'sessionrestore', 21:56:22 INFO - 'sessionrestore_no_auto_restore', 21:56:22 INFO - 'tabpaint']}, 21:56:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['tsvgx', 21:56:22 INFO - 'tsvgr_opacity', 21:56:22 INFO - 'tart', 21:56:22 INFO - 'tscrollx', 21:56:22 INFO - 'cart']}, 21:56:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:56:22 INFO - 'tsvgr_opacity', 21:56:22 INFO - 'tart', 21:56:22 INFO - 'tscrollx', 21:56:22 INFO - 'cart']}, 21:56:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'talos_options': ['--disable-e10s'], 21:56:22 INFO - 'tests': ['tp5o']}, 21:56:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'tests': ['tp5o']}, 21:56:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'talos_options': ['--disable-e10s', 21:56:22 INFO - '--xperf_path', 21:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:22 INFO - 'tests': ['tp5n']}, 21:56:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:22 INFO - 'talos_options': ['--xperf_path', 21:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:22 INFO - 'tests': ['tp5n']}}, 21:56:22 INFO - 'talos.zip': {'path': '', 21:56:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:56:22 INFO - Running post-action listener: _resource_record_post_action 21:56:22 INFO - ##### 21:56:22 INFO - ##### Finished populate-webroot step (success) 21:56:22 INFO - ##### 21:56:22 INFO - ##### 21:56:22 INFO - ##### Running create-virtualenv step. 21:56:22 INFO - ##### 21:56:22 INFO - Running pre-action listener: _resource_record_pre_action 21:56:22 INFO - Running main action method: create_virtualenv 21:56:22 INFO - Creating virtualenv /builds/slave/test/build/venv 21:56:22 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 21:56:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:56:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:56:22 INFO - Using real prefix '/tools/python27' 21:56:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:56:23 INFO - Installing distribute.............................................................................................................................................................................................done. 21:56:26 INFO - Installing pip.................done. 21:56:26 INFO - Return code: 0 21:56:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:56:26 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')]} 21:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:26 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')]} 21:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:26 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:26 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 21:56:26 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 21:56:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:26 INFO - 'HOME': '/Users/cltbld', 21:56:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:26 INFO - 'LOGNAME': 'cltbld', 21:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:26 INFO - 'MOZ_NO_REMOTE': '1', 21:56:26 INFO - 'NO_EM_RESTART': '1', 21:56:26 INFO - 'PAGER': '/bin/cat', 21:56:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:26 INFO - 'PWD': '/builds/slave/test', 21:56:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:26 INFO - 'SHELL': '/bin/bash', 21:56:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:26 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:26 INFO - 'USER': 'cltbld', 21:56:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:26 INFO - 'XPC_FLAGS': '0x0', 21:56:26 INFO - 'XPC_SERVICE_NAME': '0', 21:56:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:26 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 21:56:26 INFO - Cleaning up... 21:56:26 INFO - Return code: 0 21:56:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:56:26 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')]} 21:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:26 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')]} 21:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:26 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:26 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 21:56:26 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 21:56:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:26 INFO - 'HOME': '/Users/cltbld', 21:56:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:26 INFO - 'LOGNAME': 'cltbld', 21:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:26 INFO - 'MOZ_NO_REMOTE': '1', 21:56:26 INFO - 'NO_EM_RESTART': '1', 21:56:26 INFO - 'PAGER': '/bin/cat', 21:56:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:26 INFO - 'PWD': '/builds/slave/test', 21:56:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:26 INFO - 'SHELL': '/bin/bash', 21:56:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:26 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:26 INFO - 'USER': 'cltbld', 21:56:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:26 INFO - 'XPC_FLAGS': '0x0', 21:56:26 INFO - 'XPC_SERVICE_NAME': '0', 21:56:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:26 INFO - Downloading/unpacking psutil>=3.1.1 21:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:56:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:30 INFO - Installing collected packages: psutil 21:56:30 INFO - Running setup.py install for psutil 21:56:30 INFO - building 'psutil._psutil_osx' extension 21:56:30 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 21:56:30 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 21:56:30 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 21:56:30 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 21:56:30 INFO - building 'psutil._psutil_posix' extension 21:56:30 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 21:56:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:56:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:56:30 INFO - ^ 21:56:31 INFO - 1 warning generated. 21:56:31 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 21:56:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:31 INFO - Successfully installed psutil 21:56:31 INFO - Cleaning up... 21:56:31 INFO - Return code: 0 21:56:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:56:31 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')]} 21:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:31 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')]} 21:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:31 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:31 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 21:56:31 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 21:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:31 INFO - 'HOME': '/Users/cltbld', 21:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:31 INFO - 'LOGNAME': 'cltbld', 21:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:31 INFO - 'MOZ_NO_REMOTE': '1', 21:56:31 INFO - 'NO_EM_RESTART': '1', 21:56:31 INFO - 'PAGER': '/bin/cat', 21:56:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:31 INFO - 'PWD': '/builds/slave/test', 21:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:31 INFO - 'SHELL': '/bin/bash', 21:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:31 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:31 INFO - 'USER': 'cltbld', 21:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:31 INFO - 'XPC_FLAGS': '0x0', 21:56:31 INFO - 'XPC_SERVICE_NAME': '0', 21:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:56:31 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 21:56:31 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 21:56:31 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 21:56:31 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 21:56:31 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 21:56:31 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 21:56:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:56:34 INFO - Installing collected packages: mozsystemmonitor 21:56:34 INFO - Running setup.py install for mozsystemmonitor 21:56:34 INFO - Successfully installed mozsystemmonitor 21:56:34 INFO - Cleaning up... 21:56:34 INFO - Return code: 0 21:56:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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', '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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:56: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 jsonschema==2.5.1 21:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:34 INFO - 'HOME': '/Users/cltbld', 21:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:34 INFO - 'LOGNAME': 'cltbld', 21:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:34 INFO - 'MOZ_NO_REMOTE': '1', 21:56:34 INFO - 'NO_EM_RESTART': '1', 21:56:34 INFO - 'PAGER': '/bin/cat', 21:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:34 INFO - 'PWD': '/builds/slave/test', 21:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:34 INFO - 'SHELL': '/bin/bash', 21:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:34 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:34 INFO - 'USER': 'cltbld', 21:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:34 INFO - 'XPC_FLAGS': '0x0', 21:56:34 INFO - 'XPC_SERVICE_NAME': '0', 21:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:34 INFO - Downloading/unpacking jsonschema==2.5.1 21:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:56:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:37 INFO - Downloading functools32-3.2.3-2.tar.gz 21:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:56:37 INFO - warning: no files found matching '*.txt' 21:56:37 INFO - no previously-included directories found matching 'build' 21:56:37 INFO - no previously-included directories found matching 'dist' 21:56:37 INFO - no previously-included directories found matching '.git*' 21:56:37 INFO - Installing collected packages: jsonschema, functools32 21:56:37 INFO - Running setup.py install for functools32 21:56:37 INFO - warning: no files found matching '*.txt' 21:56:37 INFO - no previously-included directories found matching 'build' 21:56:37 INFO - no previously-included directories found matching 'dist' 21:56:37 INFO - no previously-included directories found matching '.git*' 21:56:37 INFO - Successfully installed jsonschema functools32 21:56:37 INFO - Cleaning up... 21:56:37 INFO - Return code: 0 21:56:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:56:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:37 INFO - 'HOME': '/Users/cltbld', 21:56:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:37 INFO - 'LOGNAME': 'cltbld', 21:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:37 INFO - 'MOZ_NO_REMOTE': '1', 21:56:37 INFO - 'NO_EM_RESTART': '1', 21:56:37 INFO - 'PAGER': '/bin/cat', 21:56:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:37 INFO - 'PWD': '/builds/slave/test', 21:56:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:37 INFO - 'SHELL': '/bin/bash', 21:56:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:37 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:37 INFO - 'USER': 'cltbld', 21:56:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:37 INFO - 'XPC_FLAGS': '0x0', 21:56:37 INFO - 'XPC_SERVICE_NAME': '0', 21:56:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:38 INFO - Downloading/unpacking blobuploader==1.2.4 21:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:40 INFO - Downloading blobuploader-1.2.4.tar.gz 21:56:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:56:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:56:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:41 INFO - Downloading docopt-0.6.1.tar.gz 21:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:56:41 INFO - Installing collected packages: blobuploader, requests, docopt 21:56:41 INFO - Running setup.py install for blobuploader 21:56:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:56:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:56:41 INFO - Running setup.py install for requests 21:56:42 INFO - Running setup.py install for docopt 21:56:42 INFO - Successfully installed blobuploader requests docopt 21:56:42 INFO - Cleaning up... 21:56:42 INFO - Return code: 0 21:56:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:42 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:42 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 21:56:42 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 21:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:42 INFO - 'HOME': '/Users/cltbld', 21:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:42 INFO - 'LOGNAME': 'cltbld', 21:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:42 INFO - 'MOZ_NO_REMOTE': '1', 21:56:42 INFO - 'NO_EM_RESTART': '1', 21:56:42 INFO - 'PAGER': '/bin/cat', 21:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:42 INFO - 'PWD': '/builds/slave/test', 21:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:42 INFO - 'SHELL': '/bin/bash', 21:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:42 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:42 INFO - 'USER': 'cltbld', 21:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:42 INFO - 'XPC_FLAGS': '0x0', 21:56:42 INFO - 'XPC_SERVICE_NAME': '0', 21:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:42 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-al7ufz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-MXOSdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NyO2nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-CqNsyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-syZco9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-KivdBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-LmH5JD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-2tp8WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-pxv1fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Fj100e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-MHrw6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-6Lqwrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-SCT7Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-qTZtyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-V2LWz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-IrsePS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-fdE1tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:56:44 INFO - Running setup.py install for manifestparser 21:56:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:56:44 INFO - Running setup.py install for mozcrash 21:56:44 INFO - Running setup.py install for mozdebug 21:56:45 INFO - Running setup.py install for mozdevice 21:56:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Running setup.py install for mozfile 21:56:45 INFO - Running setup.py install for mozhttpd 21:56:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Running setup.py install for mozinfo 21:56:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Running setup.py install for mozInstall 21:56:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:56:45 INFO - Running setup.py install for mozleak 21:56:45 INFO - Running setup.py install for mozlog 21:56:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Running setup.py install for moznetwork 21:56:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Running setup.py install for mozprocess 21:56:46 INFO - Running setup.py install for mozprofile 21:56:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Running setup.py install for mozrunner 21:56:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:56:46 INFO - Running setup.py install for mozscreenshot 21:56:46 INFO - Running setup.py install for moztest 21:56:47 INFO - Running setup.py install for mozversion 21:56:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:56:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:56:47 INFO - Cleaning up... 21:56:47 INFO - Return code: 0 21:56:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:47 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:47 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 21:56:47 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 21:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:47 INFO - 'HOME': '/Users/cltbld', 21:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:47 INFO - 'LOGNAME': 'cltbld', 21:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:47 INFO - 'MOZ_NO_REMOTE': '1', 21:56:47 INFO - 'NO_EM_RESTART': '1', 21:56:47 INFO - 'PAGER': '/bin/cat', 21:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:47 INFO - 'PWD': '/builds/slave/test', 21:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:47 INFO - 'SHELL': '/bin/bash', 21:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:47 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:47 INFO - 'USER': 'cltbld', 21:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:47 INFO - 'XPC_FLAGS': '0x0', 21:56:47 INFO - 'XPC_SERVICE_NAME': '0', 21:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Kig_9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:47 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)) 21:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-zKcOGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:47 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)) 21:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-5ZuGyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:47 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)) 21:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NXhOdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:47 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)) 21:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-EHM1eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-7v0g56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Bp1e8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-DD5qp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-c3g_2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-de66zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-AEjwCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-XMJqDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-sUC4w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-P5gypm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:48 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)) 21:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-vbtaiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:49 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)) 21:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:49 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-lRIGC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:49 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)) 21:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:49 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-xQZUce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:49 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)) 21:56:49 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)) 21:56:49 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)) 21:56:49 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)) 21:56:49 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)) 21:56:49 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)) 21:56:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56:51 INFO - Downloading blessings-1.6.tar.gz 21:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:56:51 INFO - Installing collected packages: blessings 21:56:51 INFO - Running setup.py install for blessings 21:56:52 INFO - Successfully installed blessings 21:56:52 INFO - Cleaning up... 21:56:52 INFO - Return code: 0 21:56:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:56:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:52 INFO - Reading from file tmpfile_stdout 21:56:52 INFO - Current package versions: 21:56:52 INFO - blessings == 1.6 21:56:52 INFO - blobuploader == 1.2.4 21:56:52 INFO - docopt == 0.6.1 21:56:52 INFO - functools32 == 3.2.3-2 21:56:52 INFO - jsonschema == 2.5.1 21:56:52 INFO - manifestparser == 1.1 21:56:52 INFO - mozInstall == 1.12 21:56:52 INFO - mozcrash == 0.17 21:56:52 INFO - mozdebug == 0.1 21:56:52 INFO - mozdevice == 0.48 21:56:52 INFO - mozfile == 1.2 21:56:52 INFO - mozhttpd == 0.7 21:56:52 INFO - mozinfo == 0.9 21:56:52 INFO - mozleak == 0.1 21:56:52 INFO - mozlog == 3.2 21:56:52 INFO - moznetwork == 0.27 21:56:52 INFO - mozprocess == 0.22 21:56:52 INFO - mozprofile == 0.28 21:56:52 INFO - mozrunner == 6.11 21:56:52 INFO - mozscreenshot == 0.1 21:56:52 INFO - mozsystemmonitor == 0.3 21:56:52 INFO - moztest == 0.7 21:56:52 INFO - mozversion == 1.4 21:56:52 INFO - psutil == 3.1.1 21:56:52 INFO - requests == 1.2.3 21:56:52 INFO - wsgiref == 0.1.2 21:56:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:52 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:52 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 21:56:52 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 21:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:52 INFO - 'HOME': '/Users/cltbld', 21:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:52 INFO - 'LOGNAME': 'cltbld', 21:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:52 INFO - 'MOZ_NO_REMOTE': '1', 21:56:52 INFO - 'NO_EM_RESTART': '1', 21:56:52 INFO - 'PAGER': '/bin/cat', 21:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:52 INFO - 'PWD': '/builds/slave/test', 21:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:52 INFO - 'SHELL': '/bin/bash', 21:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:52 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:52 INFO - 'USER': 'cltbld', 21:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:52 INFO - 'XPC_FLAGS': '0x0', 21:56:52 INFO - 'XPC_SERVICE_NAME': '0', 21:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 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)) 21:56:52 INFO - Cleaning up... 21:56:52 INFO - Return code: 0 21:56:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:52 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 0x1066b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefbf88f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, '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.RWUsk0bS95/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.0BaYpURrFH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 21:56:52 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 21:56:52 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 21:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:52 INFO - 'HOME': '/Users/cltbld', 21:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:52 INFO - 'LOGNAME': 'cltbld', 21:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:52 INFO - 'MOZ_NO_REMOTE': '1', 21:56:52 INFO - 'NO_EM_RESTART': '1', 21:56:52 INFO - 'PAGER': '/bin/cat', 21:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:52 INFO - 'PWD': '/builds/slave/test', 21:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:52 INFO - 'SHELL': '/bin/bash', 21:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:56:52 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:52 INFO - 'USER': 'cltbld', 21:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:52 INFO - 'XPC_FLAGS': '0x0', 21:56:52 INFO - 'XPC_SERVICE_NAME': '0', 21:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:56:52 INFO - Cleaning up... 21:56:52 INFO - Return code: 0 21:56:52 INFO - Running post-action listener: _resource_record_post_action 21:56:52 INFO - Running post-action listener: _start_resource_monitoring 21:56:53 INFO - Starting resource monitoring. 21:56:53 INFO - ##### 21:56:53 INFO - ##### Finished create-virtualenv step (success) 21:56:53 INFO - ##### 21:56:53 INFO - ##### 21:56:53 INFO - ##### Running install step. 21:56:53 INFO - ##### 21:56:53 INFO - Running pre-action listener: _resource_record_pre_action 21:56:53 INFO - Running main action method: install 21:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:53 INFO - Reading from file tmpfile_stdout 21:56:53 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:56:53 INFO - Reading from file tmpfile_stdout 21:56:53 INFO - Output received: 21:56:53 INFO - Usage: mozinstall [options] installer 21:56:53 INFO - Options: 21:56:53 INFO - -h, --help show this help message and exit 21:56:53 INFO - -d DEST, --destination=DEST 21:56:53 INFO - Directory to install application into. [default: 21:56:53 INFO - "/builds/slave/test"] 21:56:53 INFO - --app=APP Application being installed. [default: firefox] 21:56:53 INFO - mkdir: /builds/slave/test/build/application 21:56:53 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'] 21:56:53 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 21:57:27 INFO - Reading from file tmpfile_stdout 21:57:27 INFO - Output received: 21:57:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:57:27 INFO - Running post-action listener: _resource_record_post_action 21:57:27 INFO - ##### 21:57:27 INFO - ##### Finished install step (success) 21:57:27 INFO - ##### 21:57:27 INFO - ##### 21:57:27 INFO - ##### Running run-tests step. 21:57:27 INFO - ##### 21:57:27 INFO - Running pre-action listener: _resource_record_pre_action 21:57:27 INFO - Running main action method: run_tests 21:57:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:57:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:57:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:57:27 INFO - 2016-07-26 21:57:27.465 screenresolution[1647:8262] starting screenresolution argv=screenresolution get 21:57:27 INFO - 2016-07-26 21:57:27.468 screenresolution[1647:8262] Display 0: 1600x1200x32@60 21:57:27 INFO - 2016-07-26 21:57:27.474 screenresolution[1648:8264] starting screenresolution argv=screenresolution list 21:57:27 INFO - Available Modes on Display 0 21:57:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:57:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:57:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:57:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:57:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:57:27 INFO - Intel Iris: 21:57:27 INFO - Chipset Model: Intel Iris 21:57:27 INFO - Type: GPU 21:57:27 INFO - Bus: Built-In 21:57:27 INFO - VRAM (Dynamic, Max): 1536 MB 21:57:27 INFO - Vendor: Intel (0x8086) 21:57:27 INFO - Device ID: 0x0a2e 21:57:27 INFO - Revision ID: 0x0009 21:57:27 INFO - Displays: 21:57:27 INFO - Display: 21:57:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:57:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:57:27 INFO - Display Serial Number: 1600x1200 60 21:57:27 INFO - Main Display: Yes 21:57:27 INFO - Mirror: Off 21:57:27 INFO - Online: Yes 21:57:27 INFO - Rotation: Supported 21:57:27 INFO - Return code: 0 21:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:57:27 INFO - Python 2.7.3 21:57:27 INFO - Return code: 0 21:57:27 INFO - grabbing minidump binary from tooltool 21:57: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')]} 21:57:27 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 0x106619d30>, '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 0x10637edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106611b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:57:27 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 21:57:27 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 21:57:27 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 21:57:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:57:27 INFO - Return code: 0 21:57:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:57:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:57:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:57:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:57:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:57:27 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', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 21:57:27 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 http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0211 --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 21:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RWUsk0bS95/Render', 21:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:27 INFO - 'HOME': '/Users/cltbld', 21:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:27 INFO - 'LOGNAME': 'cltbld', 21:57:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:27 INFO - 'MOZ_NO_REMOTE': '1', 21:57:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:27 INFO - 'NO_EM_RESTART': '1', 21:57:27 INFO - 'PAGER': '/bin/cat', 21:57:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:27 INFO - 'PWD': '/builds/slave/test', 21:57:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:27 INFO - 'SHELL': '/bin/bash', 21:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0BaYpURrFH/Listeners', 21:57:27 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 21:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:27 INFO - 'USER': 'cltbld', 21:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:27 INFO - 'XPC_FLAGS': '0x0', 21:57:27 INFO - 'XPC_SERVICE_NAME': '0', 21:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:27 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', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 21:57:28 INFO - mozversion application_buildid: 20160704090257 21:57:28 INFO - mozversion application_changeset: f378a56b25ce2a2997b263c1857629f3f18d7400 21:57:28 INFO - mozversion application_display_name: Nightly 21:57:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:57:28 INFO - mozversion application_name: Firefox 21:57:28 INFO - mozversion application_remotingname: firefox 21:57:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:57:28 INFO - mozversion application_vendor: Mozilla 21:57:28 INFO - mozversion application_version: 50.0a1 21:57:28 INFO - mozversion platform_buildid: 20160704090257 21:57:28 INFO - mozversion platform_changeset: f378a56b25ce2a2997b263c1857629f3f18d7400 21:57:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:57:28 INFO - mozversion platform_version: 50.0a1 21:57:28 INFO - using testdate: 1469595448 21:57:28 INFO - actual date: 1469595448 21:57:28 INFO - starting webserver on 'localhost:49263' 21:57:28 INFO - SUITE-START | Running 2 tests 21:57:28 INFO - TEST-START | dromaeo_css 21:57:28 INFO - operating with platform_type : mac_ 21:57:28 INFO - Initialising browser for dromaeo_css test... 21:57:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpQhghqP/profile http://localhost:49263/getInfo.html) 21:57:30 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 21:57:30 INFO - PROCESS | 1658 | colorDepth:24 21:57:30 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 21:57:30 INFO - PROCESS | 1658 | __metrics 21:57:30 INFO - PROCESS | 1658 | 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" 21:57:30 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:57:31 INFO - TEST-INFO | 1658: exit 0 21:57:31 INFO - Browser initialized. 21:57:31 INFO - Running cycle 1/1 for dromaeo_css test... 21:57:31 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpQhghqP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:43 INFO - PROCESS | 1661 | RSS: Main: 243613696 21:57:43 INFO - PROCESS | 1661 | 21:58:34 INFO - PROCESS | 1661 | 1469595514439 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] 22:00:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 22:00:46 INFO - PROCESS | 1661 | RSS: Main: 373964800 22:00:46 INFO - PROCESS | 1661 | 22:03:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 22:03:50 INFO - PROCESS | 1661 | RSS: Main: 371933184 22:03:50 INFO - PROCESS | 1661 | 22:06:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 22:06:53 INFO - PROCESS | 1661 | RSS: Main: 372461568 22:06:53 INFO - PROCESS | 1661 | 22:09:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 22:09:56 INFO - PROCESS | 1661 | RSS: Main: 371486720 22:09:56 INFO - PROCESS | 1661 | 22:12:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 22:13:00 INFO - PROCESS | 1661 | RSS: Main: 340439040 22:13:00 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 22:16:03 INFO - PROCESS | 1661 | RSS: Main: 384294912 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | __start_tp_report 22:16:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 22:16:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 22:16:03 INFO - PROCESS | 1661 | |i|pagename|runs| 22:16:03 INFO - PROCESS | 1661 | |0;dojo.html;3327.417701902167;3349.1459677782163;3357.513160591714;3373.7638365314438;3375.3080618473214;5570.2480165177685;5596.608237423382;5668.121441176624;5686.947914635795;5693.174489698991;5672.886542269152;5733.111367738007;5764.913526297109;5776.335721392031;5801.854953626151;5143.12566863634;5152.639315247934;5188.221766734982;5191.169412893938;5302.3106996090455;5324.227986941891;5351.946480535204;5352.91970620441;5384.326959130109;5400.243965844785;5164.9483505165035;5214.869628259324;5216.947830521684;5255.789768409259;5285.471452854684;5171.844844654666;5274.393444753826;5284.101702710548;5297.417284098746;5300.416954135042;4302.26861433557;5136.922946155811;5173.482651734834;5215.165573508259;5287.841364759063;5704.458076482728;5776.971115144397;5780.277465316835;5809.419058094199;5834.183214349952;6088.96955515224;6109.708332504907;6274.811755647338;6275.215598050244;6276.027215781562;6098.0852872069545;6125.203723515959;6150.231221097363;6212.782552610682;6217.813465596039;5744.310682718059;5753.4534219249945;5754.769809207627;5766.798162064308;5772.422757724278;6269.780557680459;6276.121343011982;6283.05754136874;6333.809985700436;6371.872562548723;6052.182955300978;6083.00707893283;6106.992346262817;6138.508919286446;6171.981623032239;5748.252727145528;6273.498120150377;6274.403931626488;6277.623342599459;6291.96854015727;6029.457348838626;6199.535034872312;6214.782482613087;6226.844328891814;6259.436650701458;5184.7926082956255;6043.09353596964;6101.974255744823;6145.9692701536205;6296.622202667854;4911.312416261726;4923.803047878045;4924.704517728947;4936.160852654985;4962.305277261186;4406.140802543473;4449.443819522559;4454.37638730578;4454.420925279693;4507.346434766941;5652.265205523256;5665.178549110356;5710.942890571041;5729.255196824386;5737.569682273846;6292.402221788922;6318.304986451487;6319.24169099711;6337.841553961096;6351.9682401588625;5624.831255062355;5676.8864622707315;5731.8567035824435;5732.426757324234;5780.739866706008;5408;5416.295881535454;5430.755615997289;5435.157550579666;5439.292891924025;6216.2540495140875;6281.528885333527;6292.685365731695;6331.715072821734;6342.492600592034;6359.96820015897;6363.395477429637;6365.14070600463;6366.872662546816;6388.4569811566535;5294.417614062373;6316.62100273985;6335.778247761306;6344.651044192429;6347.270063942706;6082.574219804571;6119.969400152971;6121.910283036266;6135.171422439487;6166.506679465542;6307.653079080511;6314.210723659543;6327.11420401135;6336.714847831767;6341.270753863365;5958.374370691188;6035.939640603538;6094.116353128683;6100.481459075851;6134.969325153345;5945.821625351246;6009.54928380382;6016.608920420159;6057.4851137653795;6065.3934606539;4949.529794669501;5944.989351810116;5949.107633854784;5955.880882382415;5997.820065398045;6111.737529560953;6130.245269656939;6130.726976096701;6133.73253493014;6141.731496609813;5651.15232715096;5703.657780533182;5903.616264942765;5937.881242375215;5971.552133589911 22:16:03 INFO - PROCESS | 1661 | |1;ext.html;482.4572356099389;513.707186903465;531.5614617940198;540.7053156029963;543.4429709547713;30915.536266955987;31121.377572448564;32750.344993100123;32763.836180819122;32929.835350823276;44037.11925761483;45149.7742511287;45287.54712452875;48052.27921581179;48078.278825817644;9727.22182225422;9879.555420006081;9898.35660682057;9903.207743380548;9918.851217231748;8916.509591972439;9116.179543841054;9179.714839922382;9288.535573221347;10000.699979000641;10676.985686359822;12017.158798884082;12171.391430428488;12183.390830458486;12213.694657633585;12141.757164856697;12251.509939602405;12252.754944901051;12273.93863030688;12277.263364198177;8905.198532132074;11771.823422648667;11967.521299148024;12042.337671428068;12096.576619818352;42664.57335426637;42925.78537107326;42943;43004;43084.78457607723;3479.7912125272564;3487.145649315934;3558.217192217708;3727.508996401461;4060.1473690525027;32913.06049451831;33096.196712521705;33343.6665633343;33495.33009339824;33549.83225083859;3305.8347082645773;3320.153360892981;3320.900372988814;3363.0751543325537;3452.033430639425;370794;390598;391447.04276478436;391705;392740.99999999715;52252;63851;64099.679501603125;64249.67875160594;64320.356796432374;68275;68289.65855170792;68324.65837670879;68348.65825670939;68369;16293.266802993894;16887.15564221784;16902.492925212264;16908.239129239213;16917.323307067825;12977.675558110934;13058.15122017066;13068.738625227443;13070.823625873718;13078;15711.921440392725;15989.680206395808;17691.203895824718;17734.11329433523;17992.190351434216;5420.430854760272;5424.403315635277;6371.840703982345;6458.2895881453005;6463.735813414196;891.80826122384;1014.4876837197315;1043.9739006524899;1075.1667457720173;1093.4095588382183;31224.063278101694;31355.68644313573;31432;31831.363372732416;31887.84056079705;1110.2616759854607;1180.9468573914194;1194.4505527457293;1199.5381778015526;1238.8079847623633;15013.69972600542;16552.917235413744;16649.652157024862;16903.83096169047;16990.490285291213;695496;805333;814743;816110.597235062;816157.8384216201;63214;63626.68186659037;63862.36137638657;68872.6556367215;68896.31103689;1349.0691422918155;1388.2503448137893;1465.824101107874;1492.8656420922168;1507.464849978242;32704.67295327016;33004.66995330016;33108.834455827564;34605.788797392976;35130.82434587811;59870;61184;61413.69293153506;61435;61465.385346145966;504970;521335;521518.3924080355;522424;522961;38335.80832095822;38338.80830595829;42420.81843861661;46366.43602767689;47058.76470617762;49636.25545616746;49701.50298496969;50788.49211507838;51577.71055723637;51744.741276293375;41622.79188604037;41646.79176604097;41875.371869421375;42914.35628465638;43184;41323.380149298384;41765.58234417617;41870;42067;43006.139877202906;40289.99275018148;40842;41112.58887411088;41383.172336552496;41402;29592.852035739685;33410;33593.83203083969;35234.47148292829;35731.46402804012;23514.52970940606;25547.744522554538;26513;26671.19986400411;26688.59967100534 22:16:03 INFO - PROCESS | 1661 | |2;jquery.html;368.8616768711733;388.2778032858882;397.9383195604681;400.64743026136983;421.96835237357203;14893.77659335111;16479.83520164798;21413.67879481809;21459.46351341217;21916.23293184739;17362;21476.355709328705;22288.888555557198;22610;22629.660555091647;5380.704061276629;6043.425874541923;6194.101855230989;6204.47261982732;6207.503399728033;6156.722947467375;6167.321594624587;6182.196314479112;6211;6214.968925155368;5871.794487192949;6101.816945491642;6102.420270074336;6103.084537319393;6136;5914.024186009303;6073.362296958844;6076.270847498285;6168.722407491674;6170.19787427636;5271.789128434858;5288.576913846883;5298.152295632719;5304.17785243284;5313.149896016619;26665.60161355548;26791.196264111913;26880.327991800164;27122;27210.591841122397;2072.4611600983703;2238.507528343778;2241.7758224177614;2319.420144963759;2573.897044118233;10127.08479101225;10148.594056237742;10151.187904967659;10306.278560500768;10478.318909270873;1531.5711600751808;1903.7049257365131;1936.0997136331673;1936.8644194906221;1957.3834242213657;90327.54836225843;108386.99999999921;122637;129334;141835.29082354627;9004.684836030707;11416.942915285537;11675.241109327866;11767.646970590897;11834.88165118355;10692.946535267274;11308.773824523463;13825.239611821245;14541.563753087425;14607.415703371958;7132.607706576188;7643.847123057508;8253.876191857127;8578.18507241811;8600.483970961763;8508.574571271412;8549.230569248797;8596.183362580545;8686.174813392718;8687.826243475096;3920.5687374388795;3940.2907476653904;4541.84103556374;4629.537046295411;4652.55800698933;1624.3746157729358;1891.42066374576;1919.5009297582728;1923.7691477022847;1926.8651194416257;897.2687259883174;932.6083045121065;947.6683160893632;949.4682977532603;1021.7190272675074;20478;20611.896940515202;21342;22454.887725561595;22490.66264006041;897.3852910756153;936.2042264075482;943.7593413679468;967.6371360739722;988.7033889833021;10729.731756706145;11373.488193031333;11379.317240965569;11601.187916845909;11619.07047436203;508096.4595177001;512666;512719;612118;616452;148894;149827.25086374718;153751;154009.03191075745;158548;995.909479120362;1012.099231683802;1042.4735508568124;1044.1281529922448;1044.6970378590277;19807.306744263882;20065.699014514503;20124.496887577927;20142;20160.899195503927;52627;55558;58430;59568.80862382815;59719.40280597138;84206;89442;91469;91718.92474836214;94325;8116.594170291343;8172.550509721785;8283.08886022525;8394.496330220207;8506.574671266535;11753.823692644788;13451.260180690157;13479.730405391641;13651.044426890023;13659.317034148451;7852.803679908048;11555.595554155565;11732.178747487595;11809.173357864867;11838;10198.286119971532;11886.227395219283;12039.405323315426;12112.212706174423;12166;11778.823317650413;12099.637010889688;12163.148579599698;12194.878051219373;12321.691957701129;7677;8427.873581896154;8459.830803383775;8539.487630742175;8767.649294028422;11822.231554948901;11874.109441792072;11889.405529723652;12020.355521785194;12022.75954480888 22:16:03 INFO - PROCESS | 1661 | |3;mootools.html;1398.87410133088;1550.4728392346603;1575.062837611621;1847.4642353717422;2020.4140799168233;7896.802579935505;8669.739907802768;8892.110788921105;8894.510801905893;9099.181073703365;8696.73909782706;8894.155055269739;8905.955470222656;8907.376483646147;8912.242459390944;2110.155937624947;2188.627933251343;2189.7591264960843;2378.8096952243864;2416.335507735378;2208.017432242653;2235.7764223577674;2358.5282943411303;2449.865257410842;2451.142100264911;2389.330987323552;2390.067873529325;2390.1634427950253;2392.844465109771;2401.86789726565;2025.2911480981686;2309.399556115414;2363.869987150706;2366.8343215974887;2369.194473878872;2100.054975261123;2298.8620682759088;2378.595638741418;2383.9642405363934;2385.7256415512265;8830.42602230853;8915.866262006075;8916.465012099296;8917.643294268222;8918.821623567557;5277.393099793496;5412.485813847678;5413.133898576249;5421.945780542223;5425.9186112208345;3185.2992341684794;3547.840347184383;3660.762050466711;3705.0922523981526;3718.9256214875822;1042.3902017319876;1070.49151652965;1093.9015488605985;1095.4358505369662;1098.0666433531514;11684.707882302926;11747.47136378865;11751.059915206763;11756.882431175749;11786.057115430745;4536.455625324916;4649;4820.758962051954;4832.081904438145;4837.322774811459;9910.653127140515;9966.80066398668;9968.700938971842;9972.102510774064;9994.250431217688;5362.785488580491;5363.222332761728;5370.516653501204;5371.677699338053;5374.435684253097;4428.4243048403505;4735.976320118446;4928.704277743347;4929.7535123244415;4931.9753401232765;3542.362374772514;3773.3773927301686;3774.6791522720328;3859.517560304962;3871.457995880579;1150.045462266317;1157.2593540134321;1167.7022359298326;1168.5092261250295;1178.8997935175605;850.6682393866397;887.5384799904014;898.389095415124;930.9441433514011;960.0543464687278;6058.727357268934;6143.38566143391;6166.907496387557;6188.381161883775;6284;922.0594993106994;936.1855185988097;942.5617088054088;952.8189643967625;1023.3143793658267;3856.7465573688664;4066;4073.429719839225;4134.276501612206;4150.91698166035;10357.896421035844;10716.089132423675;10849.186311026704;10854.29447085937;10859.13126949842;10251.48742562869;10651.254412191072;10804.513796879159;10812.567497300177;10833.78332433347;6768.69540870662;7977.643800553919;8153.980752405949;8160.102388737234;8182.795430114175;5901.409859014064;5984.251968503937;6042.848928776816;6111.266648002268;6301.212348456443;9763.877154127365;9913.45475998827;9965.750856228653;9971.650992215238;9978;10126.59493620247;10175.389476631426;10196.3372380795;10267;10275.280730348804;6736.360045795642;7222.566646001257;7305.305995930378;7344;7357.2642735726;8453.323734101381;8472.703455379033;8546.974363076471;8548.615312310836;8550.657973680984;7502.887456688045;7504.549727016178;7573.166951635426;7714.807129821799;7766.378689704697;7427.477367139615;7489.400847932261;7491.88762168557;7634.656440460304;7640.274173953466;7100.893486597808;7132.786016419515;7175.712971481083;7177.354038136593;7188.964055179691;4849.175640141114;5082.745862706924;5113.565346945551;5114.3862736471865;5163;5025.3718285214345;5063.797448102182;5101.413337466239;5150.48495150482;5281.15501519755 22:16:03 INFO - PROCESS | 1661 | |4;prototype.html;305.76303364892203;306.1718052667534;319.7601798651012;323.48727267281356;323.9206394433364;4546.79539420726;5068.124793826285;5298.20526920962;5442.142874990627;5457.426970168125;4646.4888862225125;5178.974105129479;5348.786048558053;5410.672436451583;5476.26070480486;4124.587541245882;4166.395937564044;4184.895377615553;4192.937105943413;4193.182329445752;3649.7627654202392;3857.4406711026886;4203.4115223868685;4209.747415155085;4241.384999175118;3336.7163791077787;3732.8880133596035;3998.3802510610903;4189.937150942738;4228.534861165269;3229.5963004624423;4166.958330416717;4178.289690752549;4181.7490950542915;4199.517055538621;3059.9541006884915;3199.120241933487;3605.603383627799;3616.945745813815;3624.3657359961903;5007.899842003139;5046.293518907356;5295.099833028395;5302.367878003542;5323.16350858957;4198.118395137025;4532.750698711569;4762.64280178988;4772.785224664898;4774.40319960005;5030.2957585938;5711.857203569903;5716.114002329646;5731.197632331477;5743.770249190027;2887.7978541502102;3342.598888133415;3463.5324231228965;3888.0474283800368;3951.743136696134;4700.106979673851;5958.748662780821;6001.399860013964;6019.578629495937;6091.360407157229;3299.802011879295;3356.8825091121694;3932.488776459042;3944.8422063117728;3957.6240257175523;3398.5411969383817;4016.991504247876;4033.8184781684895;4038.071243613985;4044.919101617951;4007.8396864125107;4575.013464722382;4653.83596627563;4655.208614535537;4686.6485013624115;5568.331800184003;5713.257276554022;5750.539956803445;5757.9424205757405;5766.826995190151;1283.767583120215;1346.7912473566612;1361.8025386318927;1362.7206422683325;1373.7527245095778;816.6651672814118;857.3869683176558;861.763015170833;870.8954925408991;876.3996662286354;4560.315952607136;4951.207806750903;5445.945540544623;5464.617476776667;5489.258950041693;5195.818146364859;5449.948029103714;5569.443055694398;5862.8534286642325;5903.793367232212;4312.935305970413;4371.650267978554;4932.778024988884;5092.465291144376;5367.543758780469;2606.882690278942;3054.969450305513;3108.3938631967003;3111.0355789705263;3114.470540008204;5498.560115190775;5547.889042219133;6161.7227224775;6167.259928808482;6194.181647350445;5641.233157981499;5761.464955419602;5988.37122102173;6062.302835174011;6071.817845464643;5751.827445176651;6086.60437071589;6090.177825993612;6128.3565225651555;6169.938300616936;5682.289713785777;5907.312172961379;5920.230370051866;5934.10108765191;5947.721239933435;5732.417239225484;5805.535557155333;5812.7674893003805;5822.970885145547;5843.795467158629;5803.593748437569;5901.7639294427745;5962.284525856925;6103.1995561310405;6158;3083.1192972287336;3230.6123265208325;3390.5761779777526;3429.742769292363;3442.845071971717;4839.443464001544;4911.3724157575525;4944.307796908508;5237.738113094254;5770.2498675171955;5061.785171558726;5268.761840967293;5624.971875140598;5656.830295091154;5790.681512516852;4426.358178064227;5378.623496355218;5657.8019769307875;5697.8860422790485;5734.627249228787;4714.481407045291;5342.252084708221;5617.079118858089;5621.8594535136945;5624.437556244343;4608.907821843477;5304.655197412156;5685.379666794944;5727;5737.397573254915;5240.842774716764;5580.9441905580425;5589.785873122889;5613.915791263216;5621.437856214346 22:16:03 INFO - PROCESS | 1661 | |5;yui.html;742.0612924650318;754.4115589839923;768.5196752029981;789.9091604465481;877.3025444771406;15722.13528255947;15879.920600396981;16423.096729679863;16441.013539187643;16453.75319370209;14206.928965355159;14362.712745745079;14363.856361436383;14385.784213236784;14396.136231826098;709.8260926073093;711.4699548836098;732.2384719891306;860.6600392844823;885.7696998780332;871.9738407847743;876.5704804645742;878.0692465986075;879.5909901895617;891.4651209274448;915.6108653822099;916.9266458683289;917.179124683408;918.228687902162;919.007471930317;731.846312274423;745.0277388008647;756.8751156059244;757.1557713149854;914.049388635818;688.1845013658831;689.689639662149;691.7440546997593;707.628495040103;708.1007120956408;15072.773908391382;15112.319945602474;15123.243837808175;15141.470048548248;15175.924120379437;13943;14023.43906243749;14070.507532236323;14120.293985300797;14243.145411275253;12810.231386116862;12896.935515322457;12915.870841291655;13059.673508162372;13270.734585308337;4658.906821863544;4763.095011947719;4797.112534181188;4810;4824.517548245148;106749;106855.46572267167;107164.46417767939;110349.89650103476;111087;11192.888071119347;11257.21199516026;11257.831132533018;11358.94320528392;11403.201775875776;22105;22124.77875221227;22129.44676383071;22142.22502212415;22180.667289990663;7934.404919631051;8028.357731381476;8055.556944367999;8235.052968908532;8243.134470880592;4409.162259170811;4468.754218518013;4500.324951257272;4500.786065430602;4550.840720574764;3430.9313813723584;3540.3096396202495;3542.5926018508203;3547.733919955962;3548.6983606393746;580.5384719148287;658.5883822610868;669.3574168798009;733.8899165125274;737.7602279259262;602.9577929544889;687.669918439142;692.4114502672788;704.4681265644406;715.677944924786;9499.430034197832;9593.328467007243;9727.124558789601;9822.508874556244;9847.50762461874;663.9302873198342;695.4193248637445;699.6765364518537;701.8455939693259;701.8982247574073;4151.8546850860075;4314.935275970863;4324.56754324565;4366.43236379275;4376.124775045004;100069.49965250127;143916.5608343924;151442.24278878534;160532.19733901255;161002;145831.2708436451;147171;149520;151410;152532.23733881483;129741.35129324482;130336;130514.34742826415;131246;133406;6890.586564806128;8083.595820209083;8132.634031468717;8349.874751878606;8373.288270496832;61786.69106654438;64524.35475645184;65277.67361163354;65335.67332163499;66709.33290667225;104756;104940.47529762302;105845;106510;108595.37106943244;12024.218425802295;12060.819087713517;12077.456514456695;12196.268223906594;12785.808212877;13460.192388456724;13951;13960.650983725489;13992.720145597235;14199.787003195166;12493.500259989498;12498.187617804813;12650.43073061696;12726.872731272939;12759.425825837674;12457.439415226156;12516.624501264976;12584.433700483318;12712.745745084861;12968;12325.322107283826;12481.313527756061;12570.622881313575;12631.936840316108;12689.555865544662;10453.163746900078;10665.89334106649;10707.839382409114;10708.411037393018;10880.727981800519;10354;10737.515267668305;10764;10804.459777010961;10816.134709223086 22:16:03 INFO - PROCESS | 1661 | __end_tp_report 22:16:03 INFO - PROCESS | 1661 | __start_cc_report 22:16:03 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1240 22:16:03 INFO - PROCESS | 1661 | __end_cc_report 22:16:03 INFO - PROCESS | 1661 | __startTimestamp1469596563615__endTimestamp 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | ------- Summary: start ------- 22:16:03 INFO - PROCESS | 1661 | Number of tests: 6 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5728.82 Median:5937.88 stddev:643.56 (10.8%) stddev-sans-first:617.35 22:16:03 INFO - PROCESS | 1661 | Values: 3327.4 3349.1 3357.5 3373.8 3375.3 5570.2 5596.6 5668.1 5686.9 5693.2 5672.9 5733.1 5764.9 5776.3 5801.9 5143.1 5152.6 5188.2 5191.2 5302.3 5324.2 5351.9 5352.9 5384.3 5400.2 5164.9 5214.9 5216.9 5255.8 5285.5 5171.8 5274.4 5284.1 5297.4 5300.4 4302.3 5136.9 5173.5 5215.2 5287.8 5704.5 5777.0 5780.3 5809.4 5834.2 6089.0 6109.7 6274.8 6275.2 6276.0 6098.1 6125.2 6150.2 6212.8 6217.8 5744.3 5753.5 5754.8 5766.8 5772.4 6269.8 6276.1 6283.1 6333.8 6371.9 6052.2 6083.0 6107.0 6138.5 6172.0 5748.3 6273.5 6274.4 6277.6 6292.0 6029.5 6199.5 6214.8 6226.8 6259.4 5184.8 6043.1 6102.0 6146.0 6296.6 4911.3 4923.8 4924.7 4936.2 4962.3 4406.1 4449.4 4454.4 4454.4 4507.3 5652.3 5665.2 5710.9 5729.3 5737.6 6292.4 6318.3 6319.2 6337.8 6352.0 5624.8 5676.9 5731.9 5732.4 5780.7 5408.0 5416.3 5430.8 5435.2 5439.3 6216.3 6281.5 6292.7 6331.7 6342.5 6360.0 6363.4 6365.1 6366.9 6388.5 5294.4 6316.6 6335.8 6344.7 6347.3 6082.6 6120.0 6121.9 6135.2 6166.5 6307.7 6314.2 6327.1 6336.7 6341.3 5958.4 6035.9 6094.1 6100.5 6135.0 5945.8 6009.5 6016.6 6057.5 6065.4 4949.5 5945.0 5949.1 5955.9 5997.8 6111.7 6130.2 6130.7 6133.7 6141.7 5651.2 5703.7 5903.6 5937.9 5971.6 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:71806.96 Median:214468.40 stddev:158303.91 (73.8%) stddev-sans-first:158657.41 22:16:03 INFO - PROCESS | 1661 | Values: 482.5 513.7 531.6 540.7 543.4 30915.5 31121.4 32750.3 32763.8 32929.8 44037.1 45149.8 45287.5 48052.3 48078.3 9727.2 9879.6 9898.4 9903.2 9918.9 8916.5 9116.2 9179.7 9288.5 10000.7 10677.0 12017.2 12171.4 12183.4 12213.7 12141.8 12251.5 12252.8 12273.9 12277.3 8905.2 11771.8 11967.5 12042.3 12096.6 42664.6 42925.8 42943.0 43004.0 43084.8 3479.8 3487.1 3558.2 3727.5 4060.1 32913.1 33096.2 33343.7 33495.3 33549.8 3305.8 3320.2 3320.9 3363.1 3452.0 370794.0 390598.0 391447.0 391705.0 392741.0 52252.0 63851.0 64099.7 64249.7 64320.4 68275.0 68289.7 68324.7 68348.7 68369.0 16293.3 16887.2 16902.5 16908.2 16917.3 12977.7 13058.2 13068.7 13070.8 13078.0 15711.9 15989.7 17691.2 17734.1 17992.2 5420.4 5424.4 6371.8 6458.3 6463.7 891.8 1014.5 1044.0 1075.2 1093.4 31224.1 31355.7 31432.0 31831.4 31887.8 1110.3 1180.9 1194.5 1199.5 1238.8 15013.7 16552.9 16649.7 16903.8 16990.5 695496.0 805333.0 814743.0 816110.6 816157.8 63214.0 63626.7 63862.4 68872.7 68896.3 1349.1 1388.3 1465.8 1492.9 1507.5 32704.7 33004.7 33108.8 34605.8 35130.8 59870.0 61184.0 61413.7 61435.0 61465.4 504970.0 521335.0 521518.4 522424.0 522961.0 38335.8 38338.8 42420.8 46366.4 47058.8 49636.3 49701.5 50788.5 51577.7 51744.7 41622.8 41646.8 41875.4 42914.4 43184.0 41323.4 41765.6 41870.0 42067.0 43006.1 40290.0 40842.0 41112.6 41383.2 41402.0 29592.9 33410.0 33593.8 35234.5 35731.5 23514.5 25547.7 26513.0 26671.2 26688.6 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35069.58 Median:2446.66 stddev:94072.52 (3844.9%) stddev-sans-first:94300.33 22:16:03 INFO - PROCESS | 1661 | Values: 368.9 388.3 397.9 400.6 422.0 14893.8 16479.8 21413.7 21459.5 21916.2 17362.0 21476.4 22288.9 22610.0 22629.7 5380.7 6043.4 6194.1 6204.5 6207.5 6156.7 6167.3 6182.2 6211.0 6215.0 5871.8 6101.8 6102.4 6103.1 6136.0 5914.0 6073.4 6076.3 6168.7 6170.2 5271.8 5288.6 5298.2 5304.2 5313.1 26665.6 26791.2 26880.3 27122.0 27210.6 2072.5 2238.5 2241.8 2319.4 2573.9 10127.1 10148.6 10151.2 10306.3 10478.3 1531.6 1903.7 1936.1 1936.9 1957.4 90327.5 108387.0 122637.0 129334.0 141835.3 9004.7 11416.9 11675.2 11767.6 11834.9 10692.9 11308.8 13825.2 14541.6 14607.4 7132.6 7643.8 8253.9 8578.2 8600.5 8508.6 8549.2 8596.2 8686.2 8687.8 3920.6 3940.3 4541.8 4629.5 4652.6 1624.4 1891.4 1919.5 1923.8 1926.9 897.3 932.6 947.7 949.5 1021.7 20478.0 20611.9 21342.0 22454.9 22490.7 897.4 936.2 943.8 967.6 988.7 10729.7 11373.5 11379.3 11601.2 11619.1 508096.5 512666.0 512719.0 612118.0 616452.0 148894.0 149827.3 153751.0 154009.0 158548.0 995.9 1012.1 1042.5 1044.1 1044.7 19807.3 20065.7 20124.5 20142.0 20160.9 52627.0 55558.0 58430.0 59568.8 59719.4 84206.0 89442.0 91469.0 91718.9 94325.0 8116.6 8172.6 8283.1 8394.5 8506.6 11753.8 13451.3 13479.7 13651.0 13659.3 7852.8 11555.6 11732.2 11809.2 11838.0 10198.3 11886.2 12039.4 12112.2 12166.0 11778.8 12099.6 12163.1 12194.9 12321.7 7677.0 8427.9 8459.8 8539.5 8767.6 11822.2 11874.1 11889.4 12020.4 12022.8 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5750.32 Median:5107.49 stddev:3221.91 (63.1%) stddev-sans-first:3214.35 22:16:03 INFO - PROCESS | 1661 | Values: 1398.9 1550.5 1575.1 1847.5 2020.4 7896.8 8669.7 8892.1 8894.5 9099.2 8696.7 8894.2 8906.0 8907.4 8912.2 2110.2 2188.6 2189.8 2378.8 2416.3 2208.0 2235.8 2358.5 2449.9 2451.1 2389.3 2390.1 2390.2 2392.8 2401.9 2025.3 2309.4 2363.9 2366.8 2369.2 2100.1 2298.9 2378.6 2384.0 2385.7 8830.4 8915.9 8916.5 8917.6 8918.8 5277.4 5412.5 5413.1 5421.9 5425.9 3185.3 3547.8 3660.8 3705.1 3718.9 1042.4 1070.5 1093.9 1095.4 1098.1 11684.7 11747.5 11751.1 11756.9 11786.1 4536.5 4649.0 4820.8 4832.1 4837.3 9910.7 9966.8 9968.7 9972.1 9994.3 5362.8 5363.2 5370.5 5371.7 5374.4 4428.4 4736.0 4928.7 4929.8 4932.0 3542.4 3773.4 3774.7 3859.5 3871.5 1150.0 1157.3 1167.7 1168.5 1178.9 850.7 887.5 898.4 930.9 960.1 6058.7 6143.4 6166.9 6188.4 6284.0 922.1 936.2 942.6 952.8 1023.3 3856.7 4066.0 4073.4 4134.3 4150.9 10357.9 10716.1 10849.2 10854.3 10859.1 10251.5 10651.3 10804.5 10812.6 10833.8 6768.7 7977.6 8154.0 8160.1 8182.8 5901.4 5984.3 6042.8 6111.3 6301.2 9763.9 9913.5 9965.8 9971.7 9978.0 10126.6 10175.4 10196.3 10267.0 10275.3 6736.4 7222.6 7305.3 7344.0 7357.3 8453.3 8472.7 8547.0 8548.6 8550.7 7502.9 7504.5 7573.2 7714.8 7766.4 7427.5 7489.4 7491.9 7634.7 7640.3 7100.9 7132.8 7175.7 7177.4 7189.0 4849.2 5082.7 5113.6 5114.4 5163.0 5025.4 5063.8 5101.4 5150.5 5281.2 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4573.81 Median:5187.40 stddev:1451.87 (28.0%) stddev-sans-first:1420.16 22:16:03 INFO - PROCESS | 1661 | Values: 305.8 306.2 319.8 323.5 323.9 4546.8 5068.1 5298.2 5442.1 5457.4 4646.5 5179.0 5348.8 5410.7 5476.3 4124.6 4166.4 4184.9 4192.9 4193.2 3649.8 3857.4 4203.4 4209.7 4241.4 3336.7 3732.9 3998.4 4189.9 4228.5 3229.6 4167.0 4178.3 4181.7 4199.5 3060.0 3199.1 3605.6 3616.9 3624.4 5007.9 5046.3 5295.1 5302.4 5323.2 4198.1 4532.8 4762.6 4772.8 4774.4 5030.3 5711.9 5716.1 5731.2 5743.8 2887.8 3342.6 3463.5 3888.0 3951.7 4700.1 5958.7 6001.4 6019.6 6091.4 3299.8 3356.9 3932.5 3944.8 3957.6 3398.5 4017.0 4033.8 4038.1 4044.9 4007.8 4575.0 4653.8 4655.2 4686.6 5568.3 5713.3 5750.5 5757.9 5766.8 1283.8 1346.8 1361.8 1362.7 1373.8 816.7 857.4 861.8 870.9 876.4 4560.3 4951.2 5445.9 5464.6 5489.3 5195.8 5449.9 5569.4 5862.9 5903.8 4312.9 4371.7 4932.8 5092.5 5367.5 2606.9 3055.0 3108.4 3111.0 3114.5 5498.6 5547.9 6161.7 6167.3 6194.2 5641.2 5761.5 5988.4 6062.3 6071.8 5751.8 6086.6 6090.2 6128.4 6169.9 5682.3 5907.3 5920.2 5934.1 5947.7 5732.4 5805.5 5812.8 5823.0 5843.8 5803.6 5901.8 5962.3 6103.2 6158.0 3083.1 3230.6 3390.6 3429.7 3442.8 4839.4 4911.4 4944.3 5237.7 5770.2 5061.8 5268.8 5625.0 5656.8 5790.7 4426.4 5378.6 5657.8 5697.9 5734.6 4714.5 5342.3 5617.1 5621.9 5624.4 4608.9 5304.7 5685.4 5727.0 5737.4 5240.8 5580.9 5589.8 5613.9 5621.4 22:16:03 INFO - PROCESS | 1661 | 22:16:03 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:26229.38 Median:22114.89 stddev:42945.41 (194.2%) stddev-sans-first:43023.24 22:16:03 INFO - PROCESS | 1661 | Values: 742.1 754.4 768.5 789.9 877.3 15722.1 15879.9 16423.1 16441.0 16453.8 14206.9 14362.7 14363.9 14385.8 14396.1 709.8 711.5 732.2 860.7 885.8 872.0 876.6 878.1 879.6 891.5 915.6 916.9 917.2 918.2 919.0 731.8 745.0 756.9 757.2 914.0 688.2 689.7 691.7 707.6 708.1 15072.8 15112.3 15123.2 15141.5 15175.9 13943.0 14023.4 14070.5 14120.3 14243.1 12810.2 12896.9 12915.9 13059.7 13270.7 4658.9 4763.1 4797.1 4810.0 4824.5 106749.0 106855.5 107164.5 110349.9 111087.0 11192.9 11257.2 11257.8 11358.9 11403.2 22105.0 22124.8 22129.4 22142.2 22180.7 7934.4 8028.4 8055.6 8235.1 8243.1 4409.2 4468.8 4500.3 4500.8 4550.8 3430.9 3540.3 3542.6 3547.7 3548.7 580.5 658.6 669.4 733.9 737.8 603.0 687.7 692.4 704.5 715.7 9499.4 9593.3 9727.1 9822.5 9847.5 663.9 695.4 699.7 701.8 701.9 4151.9 4314.9 4324.6 4366.4 4376.1 100069.5 143916.6 151442.2 160532.2 161002.0 145831.3 147171.0 149520.0 151410.0 152532.2 129741.4 130336.0 130514.3 131246.0 133406.0 6890.6 8083.6 8132.6 8349.9 8373.3 61786.7 64524.4 65277.7 65335.7 66709.3 104756.0 104940.5 105845.0 106510.0 108595.4 12024.2 12060.8 12077.5 12196.3 12785.8 13460.2 13951.0 13960.7 13992.7 14199.8 12493.5 12498.2 12650.4 12726.9 12759.4 12457.4 12516.6 12584.4 12712.7 12968.0 12325.3 12481.3 12570.6 12631.9 12689.6 10453.2 10665.9 10707.8 10708.4 10880.7 10354.0 10737.5 10764.0 10804.5 10816.1 22:16:03 INFO - PROCESS | 1661 | -------- Summary: end -------- 22:16:03 INFO - PROCESS | 1661 | 22:16:04 INFO - TEST-INFO | 1661: exit 0 22:16:04 INFO - TEST-OK | dromaeo_css | took 1116157ms 22:16:04 INFO - TEST-START | kraken 22:16:04 INFO - operating with platform_type : mac_ 22:16:04 INFO - Initialising browser for kraken test... 22:16:04 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpK7KWJd/profile http://localhost:49263/getInfo.html) 22:16:06 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:16:06 INFO - PROCESS | 1668 | colorDepth:24 22:16:06 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:16:06 INFO - PROCESS | 1668 | __metrics 22:16:06 INFO - PROCESS | 1668 | 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" 22:16:06 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:16:07 INFO - TEST-INFO | 1668: exit 0 22:16:07 INFO - Browser initialized. 22:16:07 INFO - Running cycle 1/1 for kraken test... 22:16:07 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpK7KWJd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:19 INFO - PROCESS | 1669 | RSS: Main: 244715520 22:16:19 INFO - PROCESS | 1669 | 22:16:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 22:16:22 INFO - PROCESS | 1669 | RSS: Main: 294948864 22:16:22 INFO - PROCESS | 1669 | 22:16:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 22:16:25 INFO - PROCESS | 1669 | RSS: Main: 304443392 22:16:25 INFO - PROCESS | 1669 | 22:16:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 22:16:29 INFO - PROCESS | 1669 | RSS: Main: 389390336 22:16:29 INFO - PROCESS | 1669 | 22:16:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 22:16:32 INFO - PROCESS | 1669 | RSS: Main: 291352576 22:16:32 INFO - PROCESS | 1669 | 22:16:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:16:35 INFO - PROCESS | 1669 | RSS: Main: 294801408 22:16:35 INFO - PROCESS | 1669 | 22:16:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 22:16:38 INFO - PROCESS | 1669 | RSS: Main: 332201984 22:16:38 INFO - PROCESS | 1669 | 22:16:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 22:16:41 INFO - PROCESS | 1669 | RSS: Main: 333746176 22:16:41 INFO - PROCESS | 1669 | 22:16:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 22:16:45 INFO - PROCESS | 1669 | RSS: Main: 333303808 22:16:45 INFO - PROCESS | 1669 | 22:16:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:16:47 INFO - PROCESS | 1669 | RSS: Main: 311656448 22:16:47 INFO - PROCESS | 1669 | 22:16:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:16:50 INFO - PROCESS | 1669 | RSS: Main: 318689280 22:16:50 INFO - PROCESS | 1669 | 22:16:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:16:53 INFO - PROCESS | 1669 | RSS: Main: 316043264 22:16:53 INFO - PROCESS | 1669 | 22:16:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:16:56 INFO - PROCESS | 1669 | RSS: Main: 325292032 22:16:56 INFO - PROCESS | 1669 | 22:16:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:16:59 INFO - PROCESS | 1669 | RSS: Main: 326365184 22:16:59 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 22:17:01 INFO - PROCESS | 1669 | RSS: Main: 370040832 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | __start_tp_report 22:17:01 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 22:17:01 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 22:17:01 INFO - PROCESS | 1669 | |i|pagename|runs| 22:17:01 INFO - PROCESS | 1669 | |0;ai-astar;102;113;111;112;112;111;113;112;111;112 22:17:01 INFO - PROCESS | 1669 | |1;audio-beat-detection;141;115;107;180;140;108;126;107;142;105 22:17:01 INFO - PROCESS | 1669 | |2;audio-dft;181;181;187;187;183;174;185;180;183;183 22:17:01 INFO - PROCESS | 1669 | |3;audio-fft;78;73;58;105;57;55;60;59;98;73 22:17:01 INFO - PROCESS | 1669 | |4;audio-oscillator;81;92;92;71;71;67;95;69;66;100 22:17:01 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;87;84;87;84;87;84;87;84;88;84 22:17:01 INFO - PROCESS | 1669 | |6;imaging-darkroom;93;93;92;93;93;93;96;94;94;93 22:17:01 INFO - PROCESS | 1669 | |7;imaging-desaturate;86;86;86;88;83;85;86;85;86;105 22:17:01 INFO - PROCESS | 1669 | |8;json-parse-financial;55;52;53;56;55;54;56;56;54;54 22:17:01 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;65;76;67;70;75;72;68;68;79;102 22:17:01 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;83;83;81;81;82;79;81;83;83;85 22:17:01 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;141;156;154;123;145;152;142;133;181;138 22:17:01 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;161;163;162;163;163;215;177;163;162;159 22:17:01 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;65;67;67;64;67;66;68;64;66 22:17:01 INFO - PROCESS | 1669 | __end_tp_report 22:17:01 INFO - PROCESS | 1669 | __start_cc_report 22:17:01 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2020 22:17:01 INFO - PROCESS | 1669 | __end_cc_report 22:17:01 INFO - PROCESS | 1669 | __startTimestamp1469596621875__endTimestamp 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | ------- Summary: start ------- 22:17:01 INFO - PROCESS | 1669 | Number of tests: 14 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.21 (2.9%) stddev-sans-first:0.78 22:17:01 INFO - PROCESS | 1669 | Values: 102.0 113.0 111.0 112.0 112.0 111.0 113.0 112.0 111.0 112.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:133.00 stddev:24.01 (18.1%) stddev-sans-first:24.94 22:17:01 INFO - PROCESS | 1669 | Values: 141.0 115.0 107.0 180.0 140.0 108.0 126.0 107.0 142.0 105.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:3.81 (2.1%) stddev-sans-first:4.00 22:17:01 INFO - PROCESS | 1669 | Values: 181.0 181.0 187.0 187.0 183.0 174.0 185.0 180.0 183.0 183.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:71.60 Median:66.50 stddev:17.72 (26.6%) stddev-sans-first:18.64 22:17:01 INFO - PROCESS | 1669 | Values: 78.0 73.0 58.0 105.0 57.0 55.0 60.0 59.0 98.0 73.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:76.00 stddev:13.17 (17.3%) stddev-sans-first:13.96 22:17:01 INFO - PROCESS | 1669 | Values: 81.0 92.0 92.0 71.0 71.0 67.0 95.0 69.0 66.0 100.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.71 (2.0%) stddev-sans-first:1.74 22:17:01 INFO - PROCESS | 1669 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 87.0 84.0 88.0 84.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:1.07 (1.2%) stddev-sans-first:1.13 22:17:01 INFO - PROCESS | 1669 | Values: 93.0 93.0 92.0 93.0 93.0 93.0 96.0 94.0 94.0 93.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:87.60 Median:86.00 stddev:6.24 (7.3%) stddev-sans-first:6.59 22:17:01 INFO - PROCESS | 1669 | Values: 86.0 86.0 86.0 88.0 83.0 85.0 86.0 85.0 86.0 105.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.42 22:17:01 INFO - PROCESS | 1669 | Values: 55.0 52.0 53.0 56.0 55.0 54.0 56.0 56.0 54.0 54.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:74.20 Median:71.00 stddev:10.73 (15.1%) stddev-sans-first:10.85 22:17:01 INFO - PROCESS | 1669 | Values: 65.0 76.0 67.0 70.0 75.0 72.0 68.0 68.0 79.0 102.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.00 stddev:1.66 (2.0%) stddev-sans-first:1.73 22:17:01 INFO - PROCESS | 1669 | Values: 83.0 83.0 81.0 81.0 82.0 79.0 81.0 83.0 83.0 85.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:148.50 stddev:15.73 (10.6%) stddev-sans-first:16.56 22:17:01 INFO - PROCESS | 1669 | Values: 141.0 156.0 154.0 123.0 145.0 152.0 142.0 133.0 181.0 138.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:163.00 stddev:16.95 (10.4%) stddev-sans-first:17.74 22:17:01 INFO - PROCESS | 1669 | Values: 161.0 163.0 162.0 163.0 163.0 215.0 177.0 163.0 162.0 159.0 22:17:01 INFO - PROCESS | 1669 | 22:17:01 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.33 (2.0%) stddev-sans-first:1.41 22:17:01 INFO - PROCESS | 1669 | Values: 66.0 65.0 67.0 67.0 64.0 67.0 66.0 68.0 64.0 66.0 22:17:01 INFO - PROCESS | 1669 | -------- Summary: end -------- 22:17:01 INFO - PROCESS | 1669 | 22:17:02 INFO - TEST-INFO | 1669: exit 0 22:17:02 INFO - TEST-OK | kraken | took 58468ms 22:17:02 INFO - SUITE-END | took 1174s 22:17:03 INFO - Completed test suite (00:19:34) 22:17:03 INFO - javascript benchmark 22:17:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.417701902167, 3349.1459677782163, 3357.513160591714, 3373.7638365314438, 3375.3080618473214, 5570.2480165177685, 5596.608237423382, 5668.121441176624, 5686.947914635795, 5693.174489698991, 5672.886542269152, 5733.111367738007, 5764.913526297109, 5776.335721392031, 5801.854953626151, 5143.12566863634, 5152.639315247934, 5188.221766734982, 5191.169412893938, 5302.3106996090455, 5324.227986941891, 5351.946480535204, 5352.91970620441, 5384.326959130109, 5400.243965844785, 5164.9483505165035, 5214.869628259324, 5216.947830521684, 5255.789768409259, 5285.471452854684, 5171.844844654666, 5274.393444753826, 5284.101702710548, 5297.417284098746, 5300.416954135042, 4302.26861433557, 5136.922946155811, 5173.482651734834, 5215.165573508259, 5287.841364759063, 5704.458076482728, 5776.971115144397, 5780.277465316835, 5809.419058094199, 5834.183214349952, 6088.96955515224, 6109.708332504907, 6274.811755647338, 6275.215598050244, 6276.027215781562, 6098.0852872069545, 6125.203723515959, 6150.231221097363, 6212.782552610682, 6217.813465596039, 5744.310682718059, 5753.4534219249945, 5754.769809207627, 5766.798162064308, 5772.422757724278, 6269.780557680459, 6276.121343011982, 6283.05754136874, 6333.809985700436, 6371.872562548723, 6052.182955300978, 6083.00707893283, 6106.992346262817, 6138.508919286446, 6171.981623032239, 5748.252727145528, 6273.498120150377, 6274.403931626488, 6277.623342599459, 6291.96854015727, 6029.457348838626, 6199.535034872312, 6214.782482613087, 6226.844328891814, 6259.436650701458, 5184.7926082956255, 6043.09353596964, 6101.974255744823, 6145.9692701536205, 6296.622202667854, 4911.312416261726, 4923.803047878045, 4924.704517728947, 4936.160852654985, 4962.305277261186, 4406.140802543473, 4449.443819522559, 4454.37638730578, 4454.420925279693, 4507.346434766941, 5652.265205523256, 5665.178549110356, 5710.942890571041, 5729.255196824386, 5737.569682273846, 6292.402221788922, 6318.304986451487, 6319.24169099711, 6337.841553961096, 6351.9682401588625, 5624.831255062355, 5676.8864622707315, 5731.8567035824435, 5732.426757324234, 5780.739866706008, 5408.0, 5416.295881535454, 5430.755615997289, 5435.157550579666, 5439.292891924025, 6216.2540495140875, 6281.528885333527, 6292.685365731695, 6331.715072821734, 6342.492600592034, 6359.96820015897, 6363.395477429637, 6365.14070600463, 6366.872662546816, 6388.4569811566535, 5294.417614062373, 6316.62100273985, 6335.778247761306, 6344.651044192429, 6347.270063942706, 6082.574219804571, 6119.969400152971, 6121.910283036266, 6135.171422439487, 6166.506679465542, 6307.653079080511, 6314.210723659543, 6327.11420401135, 6336.714847831767, 6341.270753863365, 5958.374370691188, 6035.939640603538, 6094.116353128683, 6100.481459075851, 6134.969325153345, 5945.821625351246, 6009.54928380382, 6016.608920420159, 6057.4851137653795, 6065.3934606539, 4949.529794669501, 5944.989351810116, 5949.107633854784, 5955.880882382415, 5997.820065398045, 6111.737529560953, 6130.245269656939, 6130.726976096701, 6133.73253493014, 6141.731496609813, 5651.15232715096, 5703.657780533182, 5903.616264942765, 5937.881242375215, 5971.552133589911], "value": 5688.254507470774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.4572356099389, 513.707186903465, 531.5614617940198, 540.7053156029963, 543.4429709547713, 30915.536266955987, 31121.377572448564, 32750.344993100123, 32763.836180819122, 32929.835350823276, 44037.11925761483, 45149.7742511287, 45287.54712452875, 48052.27921581179, 48078.278825817644, 9727.22182225422, 9879.555420006081, 9898.35660682057, 9903.207743380548, 9918.851217231748, 8916.509591972439, 9116.179543841054, 9179.714839922382, 9288.535573221347, 10000.699979000641, 10676.985686359822, 12017.158798884082, 12171.391430428488, 12183.390830458486, 12213.694657633585, 12141.757164856697, 12251.509939602405, 12252.754944901051, 12273.93863030688, 12277.263364198177, 8905.198532132074, 11771.823422648667, 11967.521299148024, 12042.337671428068, 12096.576619818352, 42664.57335426637, 42925.78537107326, 42943.0, 43004.0, 43084.78457607723, 3479.7912125272564, 3487.145649315934, 3558.217192217708, 3727.508996401461, 4060.1473690525027, 32913.06049451831, 33096.196712521705, 33343.6665633343, 33495.33009339824, 33549.83225083859, 3305.8347082645773, 3320.153360892981, 3320.900372988814, 3363.0751543325537, 3452.033430639425, 370794.0, 390598.0, 391447.04276478436, 391705.0, 392740.99999999715, 52252.0, 63851.0, 64099.679501603125, 64249.67875160594, 64320.356796432374, 68275.0, 68289.65855170792, 68324.65837670879, 68348.65825670939, 68369.0, 16293.266802993894, 16887.15564221784, 16902.492925212264, 16908.239129239213, 16917.323307067825, 12977.675558110934, 13058.15122017066, 13068.738625227443, 13070.823625873718, 13078.0, 15711.921440392725, 15989.680206395808, 17691.203895824718, 17734.11329433523, 17992.190351434216, 5420.430854760272, 5424.403315635277, 6371.840703982345, 6458.2895881453005, 6463.735813414196, 891.80826122384, 1014.4876837197315, 1043.9739006524899, 1075.1667457720173, 1093.4095588382183, 31224.063278101694, 31355.68644313573, 31432.0, 31831.363372732416, 31887.84056079705, 1110.2616759854607, 1180.9468573914194, 1194.4505527457293, 1199.5381778015526, 1238.8079847623633, 15013.69972600542, 16552.917235413744, 16649.652157024862, 16903.83096169047, 16990.490285291213, 695496.0, 805333.0, 814743.0, 816110.597235062, 816157.8384216201, 63214.0, 63626.68186659037, 63862.36137638657, 68872.6556367215, 68896.31103689, 1349.0691422918155, 1388.2503448137893, 1465.824101107874, 1492.8656420922168, 1507.464849978242, 32704.67295327016, 33004.66995330016, 33108.834455827564, 34605.788797392976, 35130.82434587811, 59870.0, 61184.0, 61413.69293153506, 61435.0, 61465.385346145966, 504970.0, 521335.0, 521518.3924080355, 522424.0, 522961.0, 38335.80832095822, 38338.80830595829, 42420.81843861661, 46366.43602767689, 47058.76470617762, 49636.25545616746, 49701.50298496969, 50788.49211507838, 51577.71055723637, 51744.741276293375, 41622.79188604037, 41646.79176604097, 41875.371869421375, 42914.35628465638, 43184.0, 41323.380149298384, 41765.58234417617, 41870.0, 42067.0, 43006.139877202906, 40289.99275018148, 40842.0, 41112.58887411088, 41383.172336552496, 41402.0, 29592.852035739685, 33410.0, 33593.83203083969, 35234.47148292829, 35731.46402804012, 23514.52970940606, 25547.744522554538, 26513.0, 26671.19986400411, 26688.59967100534], "value": 21149.85323713063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.8616768711733, 388.2778032858882, 397.9383195604681, 400.64743026136983, 421.96835237357203, 14893.77659335111, 16479.83520164798, 21413.67879481809, 21459.46351341217, 21916.23293184739, 17362.0, 21476.355709328705, 22288.888555557198, 22610.0, 22629.660555091647, 5380.704061276629, 6043.425874541923, 6194.101855230989, 6204.47261982732, 6207.503399728033, 6156.722947467375, 6167.321594624587, 6182.196314479112, 6211.0, 6214.968925155368, 5871.794487192949, 6101.816945491642, 6102.420270074336, 6103.084537319393, 6136.0, 5914.024186009303, 6073.362296958844, 6076.270847498285, 6168.722407491674, 6170.19787427636, 5271.789128434858, 5288.576913846883, 5298.152295632719, 5304.17785243284, 5313.149896016619, 26665.60161355548, 26791.196264111913, 26880.327991800164, 27122.0, 27210.591841122397, 2072.4611600983703, 2238.507528343778, 2241.7758224177614, 2319.420144963759, 2573.897044118233, 10127.08479101225, 10148.594056237742, 10151.187904967659, 10306.278560500768, 10478.318909270873, 1531.5711600751808, 1903.7049257365131, 1936.0997136331673, 1936.8644194906221, 1957.3834242213657, 90327.54836225843, 108386.99999999921, 122637.0, 129334.0, 141835.29082354627, 9004.684836030707, 11416.942915285537, 11675.241109327866, 11767.646970590897, 11834.88165118355, 10692.946535267274, 11308.773824523463, 13825.239611821245, 14541.563753087425, 14607.415703371958, 7132.607706576188, 7643.847123057508, 8253.876191857127, 8578.18507241811, 8600.483970961763, 8508.574571271412, 8549.230569248797, 8596.183362580545, 8686.174813392718, 8687.826243475096, 3920.5687374388795, 3940.2907476653904, 4541.84103556374, 4629.537046295411, 4652.55800698933, 1624.3746157729358, 1891.42066374576, 1919.5009297582728, 1923.7691477022847, 1926.8651194416257, 897.2687259883174, 932.6083045121065, 947.6683160893632, 949.4682977532603, 1021.7190272675074, 20478.0, 20611.896940515202, 21342.0, 22454.887725561595, 22490.66264006041, 897.3852910756153, 936.2042264075482, 943.7593413679468, 967.6371360739722, 988.7033889833021, 10729.731756706145, 11373.488193031333, 11379.317240965569, 11601.187916845909, 11619.07047436203, 508096.4595177001, 512666.0, 512719.0, 612118.0, 616452.0, 148894.0, 149827.25086374718, 153751.0, 154009.03191075745, 158548.0, 995.909479120362, 1012.099231683802, 1042.4735508568124, 1044.1281529922448, 1044.6970378590277, 19807.306744263882, 20065.699014514503, 20124.496887577927, 20142.0, 20160.899195503927, 52627.0, 55558.0, 58430.0, 59568.80862382815, 59719.40280597138, 84206.0, 89442.0, 91469.0, 91718.92474836214, 94325.0, 8116.594170291343, 8172.550509721785, 8283.08886022525, 8394.496330220207, 8506.574671266535, 11753.823692644788, 13451.260180690157, 13479.730405391641, 13651.044426890023, 13659.317034148451, 7852.803679908048, 11555.595554155565, 11732.178747487595, 11809.173357864867, 11838.0, 10198.286119971532, 11886.227395219283, 12039.405323315426, 12112.212706174423, 12166.0, 11778.823317650413, 12099.637010889688, 12163.148579599698, 12194.878051219373, 12321.691957701129, 7677.0, 8427.873581896154, 8459.830803383775, 8539.487630742175, 8767.649294028422, 11822.231554948901, 11874.109441792072, 11889.405529723652, 12020.355521785194, 12022.75954480888], "value": 9775.187671704334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.87410133088, 1550.4728392346603, 1575.062837611621, 1847.4642353717422, 2020.4140799168233, 7896.802579935505, 8669.739907802768, 8892.110788921105, 8894.510801905893, 9099.181073703365, 8696.73909782706, 8894.155055269739, 8905.955470222656, 8907.376483646147, 8912.242459390944, 2110.155937624947, 2188.627933251343, 2189.7591264960843, 2378.8096952243864, 2416.335507735378, 2208.017432242653, 2235.7764223577674, 2358.5282943411303, 2449.865257410842, 2451.142100264911, 2389.330987323552, 2390.067873529325, 2390.1634427950253, 2392.844465109771, 2401.86789726565, 2025.2911480981686, 2309.399556115414, 2363.869987150706, 2366.8343215974887, 2369.194473878872, 2100.054975261123, 2298.8620682759088, 2378.595638741418, 2383.9642405363934, 2385.7256415512265, 8830.42602230853, 8915.866262006075, 8916.465012099296, 8917.643294268222, 8918.821623567557, 5277.393099793496, 5412.485813847678, 5413.133898576249, 5421.945780542223, 5425.9186112208345, 3185.2992341684794, 3547.840347184383, 3660.762050466711, 3705.0922523981526, 3718.9256214875822, 1042.3902017319876, 1070.49151652965, 1093.9015488605985, 1095.4358505369662, 1098.0666433531514, 11684.707882302926, 11747.47136378865, 11751.059915206763, 11756.882431175749, 11786.057115430745, 4536.455625324916, 4649.0, 4820.758962051954, 4832.081904438145, 4837.322774811459, 9910.653127140515, 9966.80066398668, 9968.700938971842, 9972.102510774064, 9994.250431217688, 5362.785488580491, 5363.222332761728, 5370.516653501204, 5371.677699338053, 5374.435684253097, 4428.4243048403505, 4735.976320118446, 4928.704277743347, 4929.7535123244415, 4931.9753401232765, 3542.362374772514, 3773.3773927301686, 3774.6791522720328, 3859.517560304962, 3871.457995880579, 1150.045462266317, 1157.2593540134321, 1167.7022359298326, 1168.5092261250295, 1178.8997935175605, 850.6682393866397, 887.5384799904014, 898.389095415124, 930.9441433514011, 960.0543464687278, 6058.727357268934, 6143.38566143391, 6166.907496387557, 6188.381161883775, 6284.0, 922.0594993106994, 936.1855185988097, 942.5617088054088, 952.8189643967625, 1023.3143793658267, 3856.7465573688664, 4066.0, 4073.429719839225, 4134.276501612206, 4150.91698166035, 10357.896421035844, 10716.089132423675, 10849.186311026704, 10854.29447085937, 10859.13126949842, 10251.48742562869, 10651.254412191072, 10804.513796879159, 10812.567497300177, 10833.78332433347, 6768.69540870662, 7977.643800553919, 8153.980752405949, 8160.102388737234, 8182.795430114175, 5901.409859014064, 5984.251968503937, 6042.848928776816, 6111.266648002268, 6301.212348456443, 9763.877154127365, 9913.45475998827, 9965.750856228653, 9971.650992215238, 9978.0, 10126.59493620247, 10175.389476631426, 10196.3372380795, 10267.0, 10275.280730348804, 6736.360045795642, 7222.566646001257, 7305.305995930378, 7344.0, 7357.2642735726, 8453.323734101381, 8472.703455379033, 8546.974363076471, 8548.615312310836, 8550.657973680984, 7502.887456688045, 7504.549727016178, 7573.166951635426, 7714.807129821799, 7766.378689704697, 7427.477367139615, 7489.400847932261, 7491.88762168557, 7634.656440460304, 7640.274173953466, 7100.893486597808, 7132.786016419515, 7175.712971481083, 7177.354038136593, 7188.964055179691, 4849.175640141114, 5082.745862706924, 5113.565346945551, 5114.3862736471865, 5163.0, 5025.3718285214345, 5063.797448102182, 5101.413337466239, 5150.48495150482, 5281.15501519755], "value": 4609.3867932254525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.76303364892203, 306.1718052667534, 319.7601798651012, 323.48727267281356, 323.9206394433364, 4546.79539420726, 5068.124793826285, 5298.20526920962, 5442.142874990627, 5457.426970168125, 4646.4888862225125, 5178.974105129479, 5348.786048558053, 5410.672436451583, 5476.26070480486, 4124.587541245882, 4166.395937564044, 4184.895377615553, 4192.937105943413, 4193.182329445752, 3649.7627654202392, 3857.4406711026886, 4203.4115223868685, 4209.747415155085, 4241.384999175118, 3336.7163791077787, 3732.8880133596035, 3998.3802510610903, 4189.937150942738, 4228.534861165269, 3229.5963004624423, 4166.958330416717, 4178.289690752549, 4181.7490950542915, 4199.517055538621, 3059.9541006884915, 3199.120241933487, 3605.603383627799, 3616.945745813815, 3624.3657359961903, 5007.899842003139, 5046.293518907356, 5295.099833028395, 5302.367878003542, 5323.16350858957, 4198.118395137025, 4532.750698711569, 4762.64280178988, 4772.785224664898, 4774.40319960005, 5030.2957585938, 5711.857203569903, 5716.114002329646, 5731.197632331477, 5743.770249190027, 2887.7978541502102, 3342.598888133415, 3463.5324231228965, 3888.0474283800368, 3951.743136696134, 4700.106979673851, 5958.748662780821, 6001.399860013964, 6019.578629495937, 6091.360407157229, 3299.802011879295, 3356.8825091121694, 3932.488776459042, 3944.8422063117728, 3957.6240257175523, 3398.5411969383817, 4016.991504247876, 4033.8184781684895, 4038.071243613985, 4044.919101617951, 4007.8396864125107, 4575.013464722382, 4653.83596627563, 4655.208614535537, 4686.6485013624115, 5568.331800184003, 5713.257276554022, 5750.539956803445, 5757.9424205757405, 5766.826995190151, 1283.767583120215, 1346.7912473566612, 1361.8025386318927, 1362.7206422683325, 1373.7527245095778, 816.6651672814118, 857.3869683176558, 861.763015170833, 870.8954925408991, 876.3996662286354, 4560.315952607136, 4951.207806750903, 5445.945540544623, 5464.617476776667, 5489.258950041693, 5195.818146364859, 5449.948029103714, 5569.443055694398, 5862.8534286642325, 5903.793367232212, 4312.935305970413, 4371.650267978554, 4932.778024988884, 5092.465291144376, 5367.543758780469, 2606.882690278942, 3054.969450305513, 3108.3938631967003, 3111.0355789705263, 3114.470540008204, 5498.560115190775, 5547.889042219133, 6161.7227224775, 6167.259928808482, 6194.181647350445, 5641.233157981499, 5761.464955419602, 5988.37122102173, 6062.302835174011, 6071.817845464643, 5751.827445176651, 6086.60437071589, 6090.177825993612, 6128.3565225651555, 6169.938300616936, 5682.289713785777, 5907.312172961379, 5920.230370051866, 5934.10108765191, 5947.721239933435, 5732.417239225484, 5805.535557155333, 5812.7674893003805, 5822.970885145547, 5843.795467158629, 5803.593748437569, 5901.7639294427745, 5962.284525856925, 6103.1995561310405, 6158.0, 3083.1192972287336, 3230.6123265208325, 3390.5761779777526, 3429.742769292363, 3442.845071971717, 4839.443464001544, 4911.3724157575525, 4944.307796908508, 5237.738113094254, 5770.2498675171955, 5061.785171558726, 5268.761840967293, 5624.971875140598, 5656.830295091154, 5790.681512516852, 4426.358178064227, 5378.623496355218, 5657.8019769307875, 5697.8860422790485, 5734.627249228787, 4714.481407045291, 5342.252084708221, 5617.079118858089, 5621.8594535136945, 5624.437556244343, 4608.907821843477, 5304.655197412156, 5685.379666794944, 5727.0, 5737.397573254915, 5240.842774716764, 5580.9441905580425, 5589.785873122889, 5613.915791263216, 5621.437856214346], "value": 4113.56202884109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.0612924650318, 754.4115589839923, 768.5196752029981, 789.9091604465481, 877.3025444771406, 15722.13528255947, 15879.920600396981, 16423.096729679863, 16441.013539187643, 16453.75319370209, 14206.928965355159, 14362.712745745079, 14363.856361436383, 14385.784213236784, 14396.136231826098, 709.8260926073093, 711.4699548836098, 732.2384719891306, 860.6600392844823, 885.7696998780332, 871.9738407847743, 876.5704804645742, 878.0692465986075, 879.5909901895617, 891.4651209274448, 915.6108653822099, 916.9266458683289, 917.179124683408, 918.228687902162, 919.007471930317, 731.846312274423, 745.0277388008647, 756.8751156059244, 757.1557713149854, 914.049388635818, 688.1845013658831, 689.689639662149, 691.7440546997593, 707.628495040103, 708.1007120956408, 15072.773908391382, 15112.319945602474, 15123.243837808175, 15141.470048548248, 15175.924120379437, 13943.0, 14023.43906243749, 14070.507532236323, 14120.293985300797, 14243.145411275253, 12810.231386116862, 12896.935515322457, 12915.870841291655, 13059.673508162372, 13270.734585308337, 4658.906821863544, 4763.095011947719, 4797.112534181188, 4810.0, 4824.517548245148, 106749.0, 106855.46572267167, 107164.46417767939, 110349.89650103476, 111087.0, 11192.888071119347, 11257.21199516026, 11257.831132533018, 11358.94320528392, 11403.201775875776, 22105.0, 22124.77875221227, 22129.44676383071, 22142.22502212415, 22180.667289990663, 7934.404919631051, 8028.357731381476, 8055.556944367999, 8235.052968908532, 8243.134470880592, 4409.162259170811, 4468.754218518013, 4500.324951257272, 4500.786065430602, 4550.840720574764, 3430.9313813723584, 3540.3096396202495, 3542.5926018508203, 3547.733919955962, 3548.6983606393746, 580.5384719148287, 658.5883822610868, 669.3574168798009, 733.8899165125274, 737.7602279259262, 602.9577929544889, 687.669918439142, 692.4114502672788, 704.4681265644406, 715.677944924786, 9499.430034197832, 9593.328467007243, 9727.124558789601, 9822.508874556244, 9847.50762461874, 663.9302873198342, 695.4193248637445, 699.6765364518537, 701.8455939693259, 701.8982247574073, 4151.8546850860075, 4314.935275970863, 4324.56754324565, 4366.43236379275, 4376.124775045004, 100069.49965250127, 143916.5608343924, 151442.24278878534, 160532.19733901255, 161002.0, 145831.2708436451, 147171.0, 149520.0, 151410.0, 152532.23733881483, 129741.35129324482, 130336.0, 130514.34742826415, 131246.0, 133406.0, 6890.586564806128, 8083.595820209083, 8132.634031468717, 8349.874751878606, 8373.288270496832, 61786.69106654438, 64524.35475645184, 65277.67361163354, 65335.67332163499, 66709.33290667225, 104756.0, 104940.47529762302, 105845.0, 106510.0, 108595.37106943244, 12024.218425802295, 12060.819087713517, 12077.456514456695, 12196.268223906594, 12785.808212877, 13460.192388456724, 13951.0, 13960.650983725489, 13992.720145597235, 14199.787003195166, 12493.500259989498, 12498.187617804813, 12650.43073061696, 12726.872731272939, 12759.425825837674, 12457.439415226156, 12516.624501264976, 12584.433700483318, 12712.745745084861, 12968.0, 12325.322107283826, 12481.313527756061, 12570.622881313575, 12631.936840316108, 12689.555865544662, 10453.163746900078, 10665.89334106649, 10707.839382409114, 10708.411037393018, 10880.727981800519, 10354.0, 10737.515267668305, 10764.0, 10804.459777010961, 10816.134709223086], "value": 7923.899142664768, "unit": "score"}], "name": "dromaeo_css", "value": 7491.0600682687, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 111.0, 112.0, 112.0, 111.0, 113.0, 112.0, 111.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 107.0, 180.0, 140.0, 108.0, 126.0, 107.0, 142.0, 105.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 181.0, 187.0, 187.0, 183.0, 174.0, 185.0, 180.0, 183.0, 183.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 105.0, 57.0, 55.0, 60.0, 59.0, 98.0, 73.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 92.0, 92.0, 71.0, 71.0, 67.0, 95.0, 69.0, 66.0, 100.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 96.0, 94.0, 94.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 88.0, 83.0, 85.0, 86.0, 85.0, 86.0, 105.0], "value": 87.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 56.0, 55.0, 54.0, 56.0, 56.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 67.0, 70.0, 75.0, 72.0, 68.0, 68.0, 79.0, 102.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 81.0, 82.0, 79.0, 81.0, 83.0, 83.0, 85.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 156.0, 154.0, 123.0, 145.0, 152.0, 142.0, 133.0, 181.0, 138.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 163.0, 163.0, 215.0, 177.0, 163.0, 162.0, 159.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 67.0, 64.0, 67.0, 66.0, 68.0, 64.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1431.1, "alertThreshold": 2.0}]} 22:17:03 INFO - Return code: 0 22:17:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:17:03 INFO - # TBPL SUCCESS # 22:17:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:17:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:17:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:17:03 INFO - 2016-07-26 22:17:03.200 screenresolution[1673:10501] starting screenresolution argv=screenresolution get 22:17:03 INFO - 2016-07-26 22:17:03.203 screenresolution[1673:10501] Display 0: 1600x1200x32@60 22:17:03 INFO - 2016-07-26 22:17:03.209 screenresolution[1674:10504] starting screenresolution argv=screenresolution list 22:17:03 INFO - Available Modes on Display 0 22:17:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:17:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:17:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:17:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:17:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:17:03 INFO - Intel Iris: 22:17:03 INFO - Chipset Model: Intel Iris 22:17:03 INFO - Type: GPU 22:17:03 INFO - Bus: Built-In 22:17:03 INFO - VRAM (Dynamic, Max): 1536 MB 22:17:03 INFO - Vendor: Intel (0x8086) 22:17:03 INFO - Device ID: 0x0a2e 22:17:03 INFO - Revision ID: 0x0009 22:17:03 INFO - Displays: 22:17:03 INFO - Display: 22:17:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:17:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:17:03 INFO - Display Serial Number: 1600x1200 60 22:17:03 INFO - Main Display: Yes 22:17:03 INFO - Mirror: Off 22:17:03 INFO - Online: Yes 22:17:03 INFO - Rotation: Supported 22:17:03 INFO - Return code: 0 22:17:03 INFO - Running post-action listener: _resource_record_post_action 22:17:03 INFO - ##### 22:17:03 INFO - ##### Finished run-tests step (success) 22:17:03 INFO - ##### 22:17:03 INFO - Running post-run listener: _resource_record_post_run 22:17:03 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31477248; Write bytes: 705248768; Read time: 195; Write time: 1926 22:17:03 INFO - TinderboxPrint: CPU usage
53.6% 22:17:03 INFO - TinderboxPrint: I/O read bytes / time
31,477,248 / 195 22:17:03 INFO - TinderboxPrint: I/O write bytes / time
705,248,768 / 1,926 22:17:03 INFO - TinderboxPrint: CPU idle
2,243.5 (46.4%) 22:17:03 INFO - TinderboxPrint: CPU system
967.1 (20.0%) 22:17:03 INFO - TinderboxPrint: CPU user
1,627.2 (33.6%) 22:17:03 INFO - TinderboxPrint: Swap in / out
435,101,696 / 0 22:17:03 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400622080; Write bytes: 394614272; Read time: 29464; Write time: 784 22:17:03 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 29445632; Write bytes: 294844416; Read time: 177; Write time: 1100 22:17:03 INFO - Running post-run listener: _upload_blobber_files 22:17:03 INFO - Blob upload gear active. 22:17:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:17:03 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 22:17:03 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'] 22:17:03 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 22:17:04 INFO - (blobuploader) - INFO - Open directory for files ... 22:17:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:05 INFO - (blobuploader) - INFO - Done attempting. 22:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:06 INFO - (blobuploader) - INFO - Done attempting. 22:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:17:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:08 INFO - (blobuploader) - INFO - Done attempting. 22:17:08 INFO - (blobuploader) - INFO - Iteration through files over. 22:17:08 INFO - Return code: 0 22:17:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:17:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:17:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92442c19a5af9b42190bf227a50f6c0f4f5fc812d5bde9bf612dca3150e850b51db0bdd73330964d7915c89e2aeaf8f840d354c9e8627019c3dff2760bf49d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27b760c049291105d1e7a511763e761d1919dcca18df318ad132fcb0e703099327e4075252cbe48c4e97f138627d7d3b7a6caa3063b10fc6205a89cec098152c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b382f16f1afab9899146844678f90501c8ddc4949e04239e2a1238946dcd87778024dd25d7780adeb5d84b1c3207cfd85c94c09e9d2297d6494a558edc8e46"} 22:17:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:17:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:17:08 INFO - Contents: 22:17:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92442c19a5af9b42190bf227a50f6c0f4f5fc812d5bde9bf612dca3150e850b51db0bdd73330964d7915c89e2aeaf8f840d354c9e8627019c3dff2760bf49d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27b760c049291105d1e7a511763e761d1919dcca18df318ad132fcb0e703099327e4075252cbe48c4e97f138627d7d3b7a6caa3063b10fc6205a89cec098152c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b382f16f1afab9899146844678f90501c8ddc4949e04239e2a1238946dcd87778024dd25d7780adeb5d84b1c3207cfd85c94c09e9d2297d6494a558edc8e46"} 22:17:08 INFO - Running post-run listener: copy_logs_to_upload_dir 22:17:08 INFO - Copying logs to upload dir... 22:17:08 INFO - mkdir: /builds/slave/test/build/upload/logs 22:17:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.441062 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-26 22:17:09.782480) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:17:09.785944) ========= 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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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/92442c19a5af9b42190bf227a50f6c0f4f5fc812d5bde9bf612dca3150e850b51db0bdd73330964d7915c89e2aeaf8f840d354c9e8627019c3dff2760bf49d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27b760c049291105d1e7a511763e761d1919dcca18df318ad132fcb0e703099327e4075252cbe48c4e97f138627d7d3b7a6caa3063b10fc6205a89cec098152c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b382f16f1afab9899146844678f90501c8ddc4949e04239e2a1238946dcd87778024dd25d7780adeb5d84b1c3207cfd85c94c09e9d2297d6494a558edc8e46"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011288 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467648177/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92442c19a5af9b42190bf227a50f6c0f4f5fc812d5bde9bf612dca3150e850b51db0bdd73330964d7915c89e2aeaf8f840d354c9e8627019c3dff2760bf49d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27b760c049291105d1e7a511763e761d1919dcca18df318ad132fcb0e703099327e4075252cbe48c4e97f138627d7d3b7a6caa3063b10fc6205a89cec098152c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b382f16f1afab9899146844678f90501c8ddc4949e04239e2a1238946dcd87778024dd25d7780adeb5d84b1c3207cfd85c94c09e9d2297d6494a558edc8e46"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:17:09.823918) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:17:09.824695) ========= 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.RWUsk0bS95/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.0BaYpURrFH/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.005202 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:17:09.877909) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:17:09.878635) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:17:09.879355) ========= ========= Total master_lag: 1.60 =========