builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1469873176.53 results: success (0) buildid: 20160730030554 builduid: c4a35e5e9f1043adb09305ded2175501 revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.530445) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.530880) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.531208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.646191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.646494) ========= 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.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-30 03:06:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-07-30 03:06:17 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.524642 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.198953) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.199292) ========= 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.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.020065 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.251525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-30 03:06:17.252365) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c3565c8b1cdb575db1c80c7791984a6490598b84 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c3565c8b1cdb575db1c80c7791984a6490598b84 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-30 03:06:17,331 truncating revision to first 12 chars 2016-07-30 03:06:17,331 Setting DEBUG logging. 2016-07-30 03:06:17,331 attempt 1/10 2016-07-30 03:06:17,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c3565c8b1cdb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 03:06:19,440 unpacking tar archive at: fx-team-c3565c8b1cdb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.363403 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-30 03:06:19.644738) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:19.645566) ========= script_repo_revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:19.646617) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:19.647538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:19.669002) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-30 03:06:19.669715) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:06:19 INFO - MultiFileLogger online at 20160730 03:06:19 in /builds/slave/test 03:06:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:06:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:06:19 INFO - {'append_to_log': False, 03:06:19 INFO - 'base_work_dir': '/builds/slave/test', 03:06:19 INFO - 'blob_upload_branch': 'Fx-Team', 03:06:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:06:19 INFO - 'branch': 'Fx-Team', 03:06:19 INFO - 'buildbot_json_path': 'buildprops.json', 03:06:19 INFO - 'config_files': ('talos/mac_config.py',), 03:06:19 INFO - 'default_actions': ('clobber', 03:06:19 INFO - 'read-buildbot-config', 03:06:19 INFO - 'download-and-extract', 03:06:19 INFO - 'populate-webroot', 03:06:19 INFO - 'create-virtualenv', 03:06:19 INFO - 'install', 03:06:19 INFO - 'run-tests'), 03:06:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:06:19 INFO - 'download_minidump_stackwalk': True, 03:06:19 INFO - 'download_symbols': 'ondemand', 03:06:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:06:19 INFO - 'tooltool.py': '/tools/tooltool.py', 03:06:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:06:19 INFO - '/tools/misc-python/virtualenv.py')}, 03:06:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:06:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:06:19 INFO - 'installer_path': 'installer.exe', 03:06:19 INFO - 'log_level': 'info', 03:06:19 INFO - 'log_name': 'talos', 03:06:19 INFO - 'log_to_console': True, 03:06:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:06:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:06:19 INFO - 'opt_config_files': (), 03:06:19 INFO - 'pip_index': False, 03:06:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:06:19 INFO - 'cmd': ('bash', 03:06:19 INFO - '-c', 03:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:06:19 INFO - 'enabled': True, 03:06:19 INFO - 'halt_on_failure': False, 03:06:19 INFO - 'name': 'check_screen_resolution'},), 03:06:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:06:19 INFO - 'cmd': ('bash', 03:06:19 INFO - '-c', 03:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:06:19 INFO - 'enabled': True, 03:06:19 INFO - 'halt_on_failure': False, 03:06:19 INFO - 'name': 'check_screen_resolution'},), 03:06:19 INFO - 'run_cmd_checks_enabled': True, 03:06:19 INFO - 'sps_profile': False, 03:06:19 INFO - 'sps_profile_interval': 0, 03:06:19 INFO - 'suite': 'dromaeojs', 03:06:19 INFO - 'system_bits': '32', 03:06:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:06:19 INFO - 'title': 't-yosemite-r7-0175', 03:06:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:06:19 INFO - 'use_talos_json': True, 03:06:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:06:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:06:19 INFO - 'work_dir': 'build'} 03:06:19 INFO - [mozharness: 2016-07-30 10:06:19.864938Z] Running clobber step. 03:06:19 INFO - Running pre-action listener: _resource_record_pre_action 03:06:19 INFO - Running main action method: clobber 03:06:19 INFO - rmtree: /builds/slave/test/build 03:06:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:06:21 INFO - Running post-action listener: _resource_record_post_action 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.280705Z] Finished clobber step (success) 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.280873Z] Running read-buildbot-config step. 03:06:21 INFO - Running pre-action listener: _resource_record_pre_action 03:06:21 INFO - Running main action method: read_buildbot_config 03:06:21 INFO - Using buildbot properties: 03:06:21 INFO - { 03:06:21 INFO - "project": "", 03:06:21 INFO - "product": "firefox", 03:06:21 INFO - "script_repo_revision": "production", 03:06:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:06:21 INFO - "repository": "", 03:06:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:06:21 INFO - "buildid": "20160730030554", 03:06:21 INFO - "stage_platform": "macosx64", 03:06:21 INFO - "basedir": "/builds/slave/test", 03:06:21 INFO - "buildnumber": 112, 03:06:21 INFO - "platform": "yosemite_r7", 03:06:21 INFO - "mozci_request": { 03:06:21 INFO - "priority": -1, 03:06:21 INFO - "type": "trigger_all_talos_jobs", 03:06:21 INFO - "times": 6 03:06:21 INFO - }, 03:06:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:06:21 INFO - "slavebuilddir": "test", 03:06:21 INFO - "branch": "fx-team", 03:06:21 INFO - "revision": "c3565c8b1cdb575db1c80c7791984a6490598b84", 03:06:21 INFO - "slavename": "t-yosemite-r7-0175", 03:06:21 INFO - "builduid": "c4a35e5e9f1043adb09305ded2175501", 03:06:21 INFO - "repo_path": "integration/fx-team" 03:06:21 INFO - } 03:06:21 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg. 03:06:21 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json. 03:06:21 INFO - Running post-action listener: _resource_record_post_action 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.285579Z] Finished read-buildbot-config step (success) 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.285710Z] Running download-and-extract step. 03:06:21 INFO - Running pre-action listener: _resource_record_pre_action 03:06:21 INFO - Running main action method: download_and_extract 03:06:21 INFO - mkdir: /builds/slave/test/build/tests 03:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/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 03:06:21 INFO - Downloaded 1199 bytes. 03:06:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - Using the following test package requirements: 03:06:21 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:06:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:06:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'jsshell-mac.zip'], 03:06:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:06:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:06:21 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:06:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:06:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:06:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/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 03:06:21 INFO - Downloaded 16300348 bytes. 03:06:21 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'] 03:06:21 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 03:06:24 INFO - Return code: 0 03:06:24 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 03:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/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 03:06:24 INFO - Downloaded 16300348 bytes. 03:06:24 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'] 03:06:24 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 03:06:28 INFO - Return code: 0 03:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.talos.tests.zip 03:06:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:06:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/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 03:06:28 INFO - Downloaded 14413725 bytes. 03:06:28 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'] 03:06:28 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 03:06:28 INFO - Return code: 0 03:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg 03:06:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:06:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:06:33 INFO - Downloaded 111598219 bytes. 03:06:33 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg 03:06:33 INFO - mkdir: /builds/slave/test/properties 03:06:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:06:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:06:33 INFO - Contents: 03:06:33 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg 03:06:33 INFO - Running post-action listener: _resource_record_post_action 03:06:33 INFO - Running post-action listener: set_extra_try_arguments 03:06:33 INFO - [mozharness: 2016-07-30 10:06:33.734945Z] Finished download-and-extract step (success) 03:06:33 INFO - [mozharness: 2016-07-30 10:06:33.735101Z] Running populate-webroot step. 03:06:33 INFO - Running pre-action listener: _resource_record_pre_action 03:06:33 INFO - Running main action method: populate_webroot 03:06:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:06:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:06:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:06:33 INFO - '--tppagecycles', 03:06:33 INFO - '7'], 03:06:33 INFO - 'tests': ['tsvgm']}}, 03:06:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:06:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:06:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:06:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:06:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:06:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:06:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['damp', 'tps']}, 03:06:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'tests': ['damp', 'tps']}, 03:06:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['dromaeo_dom']}, 03:06:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:06:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['basic_compositor_video']}, 03:06:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:06:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'other-e10s': {'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['a11yr', 03:06:33 INFO - 'ts_paint', 03:06:33 INFO - 'tpaint', 03:06:33 INFO - 'sessionrestore', 03:06:33 INFO - 'sessionrestore_no_auto_restore', 03:06:33 INFO - 'tabpaint']}, 03:06:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['tsvgx', 03:06:33 INFO - 'tsvgr_opacity', 03:06:33 INFO - 'tart', 03:06:33 INFO - 'tscrollx', 03:06:33 INFO - 'cart']}, 03:06:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:06:33 INFO - 'tsvgr_opacity', 03:06:33 INFO - 'tart', 03:06:33 INFO - 'tscrollx', 03:06:33 INFO - 'cart']}, 03:06:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'talos_options': ['--disable-e10s'], 03:06:33 INFO - 'tests': ['tp5o']}, 03:06:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'tests': ['tp5o']}, 03:06:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'talos_options': ['--disable-e10s', 03:06:33 INFO - '--xperf_path', 03:06:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:06:33 INFO - 'tests': ['tp5n']}, 03:06:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:33 INFO - 'talos_options': ['--xperf_path', 03:06:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:06:33 INFO - 'tests': ['tp5n']}}, 03:06:33 INFO - 'talos.zip': {'path': '', 03:06:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:06:33 INFO - Running post-action listener: _resource_record_post_action 03:06:33 INFO - [mozharness: 2016-07-30 10:06:33.755742Z] Finished populate-webroot step (success) 03:06:33 INFO - [mozharness: 2016-07-30 10:06:33.755888Z] Running create-virtualenv step. 03:06:33 INFO - Running pre-action listener: _resource_record_pre_action 03:06:33 INFO - Running main action method: create_virtualenv 03:06:33 INFO - Creating virtualenv /builds/slave/test/build/venv 03:06:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:06:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:06:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:06:33 INFO - Using real prefix '/tools/python27' 03:06:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:06:34 INFO - Installing distribute.............................................................................................................................................................................................done. 03:06:37 INFO - Installing pip.................done. 03:06:37 INFO - Return code: 0 03:06:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06: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', 'pip>=1.5'] in /builds/slave/test/build 03:06: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 pip>=1.5 03:06:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:37 INFO - 'HOME': '/Users/cltbld', 03:06:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:37 INFO - 'LOGNAME': 'cltbld', 03:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:37 INFO - 'MOZ_NO_REMOTE': '1', 03:06:37 INFO - 'NO_EM_RESTART': '1', 03:06:37 INFO - 'PAGER': '/bin/cat', 03:06:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:37 INFO - 'PWD': '/builds/slave/test', 03:06:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:37 INFO - 'SHELL': '/bin/bash', 03:06:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:37 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:37 INFO - 'USER': 'cltbld', 03:06:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:37 INFO - 'XPC_FLAGS': '0x0', 03:06:37 INFO - 'XPC_SERVICE_NAME': '0', 03:06:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:06:37 INFO - Cleaning up... 03:06:37 INFO - Return code: 0 03:06:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06: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', '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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:06: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 psutil>=3.1.1 03:06:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:37 INFO - 'HOME': '/Users/cltbld', 03:06:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:37 INFO - 'LOGNAME': 'cltbld', 03:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:37 INFO - 'MOZ_NO_REMOTE': '1', 03:06:37 INFO - 'NO_EM_RESTART': '1', 03:06:37 INFO - 'PAGER': '/bin/cat', 03:06:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:37 INFO - 'PWD': '/builds/slave/test', 03:06:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:37 INFO - 'SHELL': '/bin/bash', 03:06:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:37 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:37 INFO - 'USER': 'cltbld', 03:06:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:37 INFO - 'XPC_FLAGS': '0x0', 03:06:37 INFO - 'XPC_SERVICE_NAME': '0', 03:06:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:38 INFO - Downloading/unpacking psutil>=3.1.1 03:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:06:41 INFO - Installing collected packages: psutil 03:06:41 INFO - Running setup.py install for psutil 03:06:41 INFO - building 'psutil._psutil_osx' extension 03:06:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:06:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:06:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:06:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:06:42 INFO - building 'psutil._psutil_posix' extension 03:06:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:06:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:06:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:06:42 INFO - ^ 03:06:42 INFO - 1 warning generated. 03:06:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:06:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:06:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:06:42 INFO - Successfully installed psutil 03:06:42 INFO - Cleaning up... 03:06:42 INFO - Return code: 0 03:06:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:06: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')]} 03:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06: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')]} 03:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:42 INFO - 'HOME': '/Users/cltbld', 03:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:42 INFO - 'LOGNAME': 'cltbld', 03:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:42 INFO - 'MOZ_NO_REMOTE': '1', 03:06:42 INFO - 'NO_EM_RESTART': '1', 03:06:42 INFO - 'PAGER': '/bin/cat', 03:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:42 INFO - 'PWD': '/builds/slave/test', 03:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:42 INFO - 'SHELL': '/bin/bash', 03:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:42 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:42 INFO - 'USER': 'cltbld', 03:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:42 INFO - 'XPC_FLAGS': '0x0', 03:06:42 INFO - 'XPC_SERVICE_NAME': '0', 03:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:06:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:06:45 INFO - Installing collected packages: mozsystemmonitor 03:06:45 INFO - Running setup.py install for mozsystemmonitor 03:06:45 INFO - Successfully installed mozsystemmonitor 03:06:45 INFO - Cleaning up... 03:06:45 INFO - Return code: 0 03:06:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:45 INFO - 'HOME': '/Users/cltbld', 03:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:45 INFO - 'LOGNAME': 'cltbld', 03:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:45 INFO - 'MOZ_NO_REMOTE': '1', 03:06:45 INFO - 'NO_EM_RESTART': '1', 03:06:45 INFO - 'PAGER': '/bin/cat', 03:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:45 INFO - 'PWD': '/builds/slave/test', 03:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:45 INFO - 'SHELL': '/bin/bash', 03:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:45 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:45 INFO - 'USER': 'cltbld', 03:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:45 INFO - 'XPC_FLAGS': '0x0', 03:06:45 INFO - 'XPC_SERVICE_NAME': '0', 03:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:06:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:06:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:06:48 INFO - warning: no files found matching '*.txt' 03:06:48 INFO - no previously-included directories found matching 'build' 03:06:48 INFO - no previously-included directories found matching 'dist' 03:06:48 INFO - no previously-included directories found matching '.git*' 03:06:48 INFO - Installing collected packages: jsonschema, functools32 03:06:48 INFO - Running setup.py install for functools32 03:06:48 INFO - warning: no files found matching '*.txt' 03:06:48 INFO - no previously-included directories found matching 'build' 03:06:48 INFO - no previously-included directories found matching 'dist' 03:06:48 INFO - no previously-included directories found matching '.git*' 03:06:48 INFO - Successfully installed jsonschema functools32 03:06:48 INFO - Cleaning up... 03:06:48 INFO - Return code: 0 03:06:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:48 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:48 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:06:48 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 functools32==3.2.3-2 03:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:48 INFO - 'HOME': '/Users/cltbld', 03:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:48 INFO - 'LOGNAME': 'cltbld', 03:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:48 INFO - 'MOZ_NO_REMOTE': '1', 03:06:48 INFO - 'NO_EM_RESTART': '1', 03:06:48 INFO - 'PAGER': '/bin/cat', 03:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:48 INFO - 'PWD': '/builds/slave/test', 03:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:48 INFO - 'SHELL': '/bin/bash', 03:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:48 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:48 INFO - 'USER': 'cltbld', 03:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:48 INFO - 'XPC_FLAGS': '0x0', 03:06:48 INFO - 'XPC_SERVICE_NAME': '0', 03:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:06:49 INFO - Cleaning up... 03:06:49 INFO - Return code: 0 03:06:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:49 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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:49 INFO - 'HOME': '/Users/cltbld', 03:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:49 INFO - 'LOGNAME': 'cltbld', 03:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:49 INFO - 'MOZ_NO_REMOTE': '1', 03:06:49 INFO - 'NO_EM_RESTART': '1', 03:06:49 INFO - 'PAGER': '/bin/cat', 03:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:49 INFO - 'PWD': '/builds/slave/test', 03:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:49 INFO - 'SHELL': '/bin/bash', 03:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:49 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:49 INFO - 'USER': 'cltbld', 03:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:49 INFO - 'XPC_FLAGS': '0x0', 03:06:49 INFO - 'XPC_SERVICE_NAME': '0', 03:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:06: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 03:06: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 03:06: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 03:06: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 03:06: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 03:06: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 03:06:51 INFO - Downloading blobuploader-1.2.4.tar.gz 03:06:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:06:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:06:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:53 INFO - Downloading docopt-0.6.1.tar.gz 03:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:06:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:06:53 INFO - Running setup.py install for blobuploader 03:06:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:06:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:06:53 INFO - Running setup.py install for requests 03:06:53 INFO - Running setup.py install for docopt 03:06:54 INFO - Successfully installed blobuploader requests docopt 03:06:54 INFO - Cleaning up... 03:06:54 INFO - Return code: 0 03:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:54 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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:54 INFO - 'HOME': '/Users/cltbld', 03:06:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:54 INFO - 'LOGNAME': 'cltbld', 03:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:54 INFO - 'MOZ_NO_REMOTE': '1', 03:06:54 INFO - 'NO_EM_RESTART': '1', 03:06:54 INFO - 'PAGER': '/bin/cat', 03:06:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:54 INFO - 'PWD': '/builds/slave/test', 03:06:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:54 INFO - 'SHELL': '/bin/bash', 03:06:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:54 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:54 INFO - 'USER': 'cltbld', 03:06:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:54 INFO - 'XPC_FLAGS': '0x0', 03:06:54 INFO - 'XPC_SERVICE_NAME': '0', 03:06:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-A4B9gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-gk_Fg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-OK8Cyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-yqmOcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-nDBM6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:06:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-oTN6On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-10uac3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-0J3PHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-fsrCcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kw7sSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KogIA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ktbY8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-nI9TAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QdVgdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-dzr2Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:06:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-6AmJvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:06:56 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-vAacnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:06:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:06:56 INFO - Running setup.py install for manifestparser 03:06:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:06:56 INFO - Running setup.py install for mozcrash 03:06:56 INFO - Running setup.py install for mozdebug 03:06:56 INFO - Running setup.py install for mozdevice 03:06:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:06:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:06:56 INFO - Running setup.py install for mozfile 03:06:56 INFO - Running setup.py install for mozhttpd 03:06:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Running setup.py install for mozinfo 03:06:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Running setup.py install for mozInstall 03:06:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Running setup.py install for mozleak 03:06:57 INFO - Running setup.py install for mozlog 03:06:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Running setup.py install for moznetwork 03:06:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:06:57 INFO - Running setup.py install for mozprocess 03:06:57 INFO - Running setup.py install for mozprofile 03:06:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:06:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:06:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:06:58 INFO - Running setup.py install for mozrunner 03:06:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:06:58 INFO - Running setup.py install for mozscreenshot 03:06:58 INFO - Running setup.py install for moztest 03:06:58 INFO - Running setup.py install for mozversion 03:06:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:06:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:06:58 INFO - Cleaning up... 03:06:58 INFO - Return code: 0 03:06:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:58 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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:06:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:06:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:58 INFO - 'HOME': '/Users/cltbld', 03:06:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:58 INFO - 'LOGNAME': 'cltbld', 03:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:58 INFO - 'MOZ_NO_REMOTE': '1', 03:06:58 INFO - 'NO_EM_RESTART': '1', 03:06:58 INFO - 'PAGER': '/bin/cat', 03:06:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:58 INFO - 'PWD': '/builds/slave/test', 03:06:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:58 INFO - 'SHELL': '/bin/bash', 03:06:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:06:58 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:06:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:58 INFO - 'USER': 'cltbld', 03:06:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:58 INFO - 'XPC_FLAGS': '0x0', 03:06:58 INFO - 'XPC_SERVICE_NAME': '0', 03:06:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-W6MGdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZZPCTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QRNaG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_rkPhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-01Y6Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_NO6W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-yfW1TN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-alLyq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QdFGot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:06:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-WeYrFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-F0cdg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Bm6S0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-vKuJx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-l_4bRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-GhfwuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-f68mVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-f02Cf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:07:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:07:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:03 INFO - Downloading blessings-1.6.tar.gz 03:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:07:03 INFO - Installing collected packages: blessings 03:07:03 INFO - Running setup.py install for blessings 03:07:03 INFO - Successfully installed blessings 03:07:03 INFO - Cleaning up... 03:07:03 INFO - Return code: 0 03:07:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:07:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:07:03 INFO - Reading from file tmpfile_stdout 03:07:03 INFO - Current package versions: 03:07:03 INFO - blessings == 1.6 03:07:03 INFO - blobuploader == 1.2.4 03:07:03 INFO - docopt == 0.6.1 03:07:03 INFO - functools32 == 3.2.3-2 03:07:03 INFO - jsonschema == 2.5.1 03:07:03 INFO - manifestparser == 1.1 03:07:03 INFO - mozInstall == 1.12 03:07:03 INFO - mozcrash == 0.17 03:07:03 INFO - mozdebug == 0.1 03:07:03 INFO - mozdevice == 0.48 03:07:03 INFO - mozfile == 1.2 03:07:03 INFO - mozhttpd == 0.7 03:07:03 INFO - mozinfo == 0.9 03:07:03 INFO - mozleak == 0.1 03:07:03 INFO - mozlog == 3.3 03:07:03 INFO - moznetwork == 0.27 03:07:03 INFO - mozprocess == 0.23 03:07:03 INFO - mozprofile == 0.28 03:07:03 INFO - mozrunner == 6.12 03:07:03 INFO - mozscreenshot == 0.1 03:07:03 INFO - mozsystemmonitor == 0.3 03:07:03 INFO - moztest == 0.7 03:07:03 INFO - mozversion == 1.4 03:07:03 INFO - psutil == 3.1.1 03:07:03 INFO - requests == 1.2.3 03:07:03 INFO - wsgiref == 0.1.2 03:07:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:03 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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:07:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:07:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:07:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:03 INFO - 'HOME': '/Users/cltbld', 03:07:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:03 INFO - 'LOGNAME': 'cltbld', 03:07:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:03 INFO - 'MOZ_NO_REMOTE': '1', 03:07:03 INFO - 'NO_EM_RESTART': '1', 03:07:03 INFO - 'PAGER': '/bin/cat', 03:07:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:07:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:03 INFO - 'PWD': '/builds/slave/test', 03:07:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:03 INFO - 'SHELL': '/bin/bash', 03:07:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:07:03 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:07:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:03 INFO - 'USER': 'cltbld', 03:07:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:03 INFO - 'XPC_FLAGS': '0x0', 03:07:03 INFO - 'XPC_SERVICE_NAME': '0', 03:07:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:07:04 INFO - Cleaning up... 03:07:04 INFO - Return code: 0 03:07:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:07: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')]} 03:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07: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')]} 03:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:04 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 0x1072cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107362618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb795bdab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, '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.39CKg4C0jn/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.ktkII0Fy1m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:07:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:04 INFO - 'HOME': '/Users/cltbld', 03:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:04 INFO - 'LOGNAME': 'cltbld', 03:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:04 INFO - 'MOZ_NO_REMOTE': '1', 03:07:04 INFO - 'NO_EM_RESTART': '1', 03:07:04 INFO - 'PAGER': '/bin/cat', 03:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:04 INFO - 'PWD': '/builds/slave/test', 03:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:04 INFO - 'SHELL': '/bin/bash', 03:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:07:04 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:04 INFO - 'USER': 'cltbld', 03:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:04 INFO - 'XPC_FLAGS': '0x0', 03:07:04 INFO - 'XPC_SERVICE_NAME': '0', 03:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:07:04 INFO - Cleaning up... 03:07:04 INFO - Return code: 0 03:07:04 INFO - Running post-action listener: _resource_record_post_action 03:07:04 INFO - Running post-action listener: _start_resource_monitoring 03:07:04 INFO - Starting resource monitoring. 03:07:04 INFO - [mozharness: 2016-07-30 10:07:04.547224Z] Finished create-virtualenv step (success) 03:07:04 INFO - [mozharness: 2016-07-30 10:07:04.548235Z] Running install step. 03:07:04 INFO - Running pre-action listener: _resource_record_pre_action 03:07:04 INFO - Running main action method: install 03:07:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:07:04 INFO - Reading from file tmpfile_stdout 03:07:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:07:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:07:04 INFO - Reading from file tmpfile_stdout 03:07:04 INFO - Output received: 03:07:04 INFO - Usage: mozinstall [options] installer 03:07:04 INFO - Options: 03:07:04 INFO - -h, --help show this help message and exit 03:07:04 INFO - -d DEST, --destination=DEST 03:07:04 INFO - Directory to install application into. [default: 03:07:04 INFO - "/builds/slave/test"] 03:07:04 INFO - --app=APP Application being installed. [default: firefox] 03:07:04 INFO - mkdir: /builds/slave/test/build/application 03:07:04 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'] 03:07:04 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 03:07:39 INFO - Reading from file tmpfile_stdout 03:07:39 INFO - Output received: 03:07:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:07:39 INFO - Running post-action listener: _resource_record_post_action 03:07:39 INFO - [mozharness: 2016-07-30 10:07:39.098166Z] Finished install step (success) 03:07:39 INFO - [mozharness: 2016-07-30 10:07:39.098318Z] Running run-tests step. 03:07:39 INFO - Running pre-action listener: _resource_record_pre_action 03:07:39 INFO - Running main action method: run_tests 03:07:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:39 INFO - 2016-07-30 03:07:39.112 screenresolution[2235:27035] starting screenresolution argv=screenresolution get 03:07:39 INFO - 2016-07-30 03:07:39.115 screenresolution[2235:27035] Display 0: 1600x1200x32@60 03:07:39 INFO - 2016-07-30 03:07:39.120 screenresolution[2236:27037] starting screenresolution argv=screenresolution list 03:07:39 INFO - Available Modes on Display 0 03:07:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:39 INFO - Intel Iris: 03:07:39 INFO - Chipset Model: Intel Iris 03:07:39 INFO - Type: GPU 03:07:39 INFO - Bus: Built-In 03:07:39 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:39 INFO - Vendor: Intel (0x8086) 03:07:39 INFO - Device ID: 0x0a2e 03:07:39 INFO - Revision ID: 0x0009 03:07:39 INFO - Displays: 03:07:39 INFO - Display: 03:07:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:39 INFO - Display Serial Number: 1600x1200 60 03:07:39 INFO - Main Display: Yes 03:07:39 INFO - Mirror: Off 03:07:39 INFO - Online: Yes 03:07:39 INFO - Rotation: Supported 03:07:39 INFO - Return code: 0 03:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:07:39 INFO - Python 2.7.3 03:07:39 INFO - Return code: 0 03:07:39 INFO - grabbing minidump binary from tooltool 03:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:39 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 0x107231d30>, '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 0x106f96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107229b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:07:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:07:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:07:39 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 03:07:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:07:39 INFO - Return code: 0 03:07:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:07:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:07:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:07:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:07:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CKg4C0jn/Render', 03:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:39 INFO - 'HOME': '/Users/cltbld', 03:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:39 INFO - 'LOGNAME': 'cltbld', 03:07:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:07:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:39 INFO - 'MOZ_NO_REMOTE': '1', 03:07:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:07:39 INFO - 'NO_EM_RESTART': '1', 03:07:39 INFO - 'PAGER': '/bin/cat', 03:07:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:39 INFO - 'PWD': '/builds/slave/test', 03:07:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:39 INFO - 'SHELL': '/bin/bash', 03:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktkII0Fy1m/Listeners', 03:07:39 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:39 INFO - 'USER': 'cltbld', 03:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:39 INFO - 'XPC_FLAGS': '0x0', 03:07:39 INFO - 'XPC_SERVICE_NAME': '0', 03:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:07:39 INFO - mozversion application_buildid: 20160730005025 03:07:39 INFO - mozversion application_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:39 INFO - mozversion application_display_name: Nightly 03:07:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:07:39 INFO - mozversion application_name: Firefox 03:07:39 INFO - mozversion application_remotingname: firefox 03:07:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:07:39 INFO - mozversion application_vendor: Mozilla 03:07:39 INFO - mozversion application_version: 50.0a1 03:07:39 INFO - mozversion platform_buildid: 20160730005025 03:07:39 INFO - mozversion platform_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:07:39 INFO - mozversion platform_version: 50.0a1 03:07:39 INFO - using testdate: 1469873259 03:07:39 INFO - actual date: 1469873259 03:07:39 INFO - starting webserver on 'localhost:58594' 03:07:39 INFO - SUITE-START | Running 2 tests 03:07:39 INFO - TEST-START | dromaeo_css 03:07:39 INFO - operating with platform_type : mac_ 03:07:39 INFO - Initialising browser for dromaeo_css test... 03:07:39 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpYmBuqa/profile http://localhost:58594/getInfo.html) 03:07:42 INFO - PROCESS | 2246 | __metrics Screen width/height:1600/1200 03:07:42 INFO - PROCESS | 2246 | colorDepth:24 03:07:42 INFO - PROCESS | 2246 | Browser inner width/height: 1024/689 03:07:42 INFO - PROCESS | 2246 | __metrics 03:07:42 INFO - PROCESS | 2246 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:07:42 INFO - PROCESS | 2246 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:07:42 INFO - TEST-INFO | 2246: exit 0 03:07:42 INFO - Browser initialized. 03:07:42 INFO - Running cycle 1/1 for dromaeo_css test... 03:07:42 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpYmBuqa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:54 INFO - PROCESS | 2248 | RSS: Main: 243576832 03:07:54 INFO - PROCESS | 2248 | 03:08:45 INFO - PROCESS | 2248 | 1469873325783 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:10:57 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58594/tests/dromaeo/cssquery-ext.html) 03:10:59 INFO - PROCESS | 2248 | RSS: Main: 387162112 03:10:59 INFO - PROCESS | 2248 | 03:14:02 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-ext.html (next: http://localhost:58594/tests/dromaeo/cssquery-jquery.html) 03:14:03 INFO - PROCESS | 2248 | RSS: Main: 390504448 03:14:03 INFO - PROCESS | 2248 | 03:17:05 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58594/tests/dromaeo/cssquery-mootools.html) 03:17:05 INFO - PROCESS | 2248 | RSS: Main: 366596096 03:17:05 INFO - PROCESS | 2248 | 03:20:08 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58594/tests/dromaeo/cssquery-prototype.html) 03:20:08 INFO - PROCESS | 2248 | RSS: Main: 375279616 03:20:08 INFO - PROCESS | 2248 | 03:23:12 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58594/tests/dromaeo/cssquery-yui.html) 03:23:13 INFO - PROCESS | 2248 | RSS: Main: 356274176 03:23:13 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:58594/tests/dromaeo/cssquery-yui.html (next: http://localhost:58594/tests/dromaeo/cssquery-dojo.html) 03:26:16 INFO - PROCESS | 2248 | RSS: Main: 573992960 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | __start_tp_report 03:26:16 INFO - PROCESS | 2248 | _x_x_mozilla_page_load 03:26:16 INFO - PROCESS | 2248 | _x_x_mozilla_page_load_details 03:26:16 INFO - PROCESS | 2248 | |i|pagename|runs| 03:26:16 INFO - PROCESS | 2248 | |0;dojo.html;3258.266889949761;3264.6735326467347;3285.983570082149;3304.7356211503084;3327.584051993501;5476.890462190754;5518.117101263799;5597.972010139949;5599.300087489064;5614.326280846294;5582.888342233153;5617.887642247163;5636.549076073915;5662;5668.036433806253;5100.33695619569;5120.078385890538;5156.381234251895;5190.091733946563;5214.947850521484;5200.895982080356;5226.581873450134;5232.764525596357;5237.633365664386;5248.134057880445;5149.124648809711;5160.716160611165;5170.121079416489;5175.844724658247;5235.921461178085;5225.973870130663;5267.947320526784;5275.393329767049;5281.287026251445;5331.573474122061;5028.698278103326;5040.344755181803;5086.46592107827;5164.896702065976;5175.870603234949;4062.7562346259024;5463.3717122530625;5478.205660179252;5612.410696876851;5622.156676498517;6246.531510136758;6249.343818899041;6251;6259.812205633838;6269.279032911182;6129.69351532426;6164.630122192638;6278.717457714414;6306.621602703853;6307.463865571432;5803.390643982406;5822.767089316422;5830.737616807254;5832.387599302055;5841.32824725162;6314.0528920661345;6325.209348831396;6327.082573026885;6341.778037768656;6352.333005034498;5965.075413310944;6018.729157187937;6070.605410648293;6155.261368635793;6203.627782333075;6257.186565746424;6299.464545513591;6313.2424109107205;6318.147050148171;6319.58922670025;6163.229596300463;6221.564490485713;6233.189685340877;6239.563230573816;6274.435300822856;6086.726097325631;6108.251008765869;6206.534509911775;6279.183706118175;6288.339724328879;4879.829205977774;4892.730899800546;4895.31465594817;4896.314515967767;4896.559309662148;4350.891227719332;4413.624841888475;4444.377787109807;4449.243628583149;4450.109978004412;5676.574256930747;5685.5167310778215;5739.454751798572;5742.827715168552;5745.195672605839;6249.25008998923;6272.1219029335925;6294.055891616203;6299.527535434769;6323.367663233732;5577.972110139423;5669.858253543612;5675.631083979528;5677.659340439587;5686.061799802987;4513.210188217049;5338.225957236256;5394.9460505394445;5408.188771684278;5410.97294513525;6217.9689101555105;6244.469220116341;6265.843353916189;6282.183316168869;6301.4328710416285;6284.685765711788;6291.937080629135;6292.496600271876;6294.464970477561;6321.620702757849;6272.310045894857;6279.843003924939;6295.622262664255;6296.590721603081;6297;6115.908261375994;6121.209103132385;6140.785072522441;6220.564560480723;6235.376462353728;6271.216097987752;6289.842753931189;6295.244570651551;6298.212723409574;6300.117983482408;5905.4685078343155;6031.728572214173;6069.817905462844;6110.175126357884;6134.754609815557;5949.464548190511;5952.434518720715;6039.396060393926;6045.667488288186;6104.06507294887;5911.733971971357;5918.970405147947;5957.16599676037;5958.880822383441;5982.820515384546;6098.566012856479;6125.815438131279;6134.356392119172;6135.877282454237;6137.3248942617165;5676.4323567642905;5703.971480142573;5750.712464376848;5806.4193580641595;5811.680357580373 03:26:16 INFO - PROCESS | 2248 | |1;ext.html;439.3146691161787;534.3320848938827;538.8329617818475;539.344696194124;552.039451354643;23876;26021.829017694177;30333.544996825065;30526.23684407891;32041.19897002576;43011.56988430115;43918;43955.34066988998;46380.768096159474;46501;8779.473231606124;9238.538073096352;9650.517474126284;9785.217182625409;9843.557039933185;7583.9241607584045;8812.559372031374;9000.144986226298;9599.424034557915;9708.95145524275;9038.593263303133;9099.272058235358;11644.41777911101;11835.289882607029;11900.285982841056;9917.404955702645;12015.339156346397;12027.158098933121;12027.93986030073;12037.638870833889;11722.94138529302;11803.763924721457;11803.822942655781;11810.173287869853;11822.694574557965;32486.83756581202;32745.83627081873;32785.6721432785;34136.82931585351;40775.59224407777;3409.590849098099;3513.367593833109;3692.2061756722433;3853.0560012796764;3923.2545816294814;31559.50881768793;31701.52447713286;32590.02229933082;32704.672953270638;32908.67091329104;3182.138707082336;3205.2307446212976;3231.466807976681;3258.625258095302;3354.111160542458;534562.3271883694;536636;537610.3119484417;539167;539231;59452.81094378088;59684.40315596875;59920;59970;60077;34915.12712182165;35220.64779352173;35456.822715886774;36623;36718;16689.916550417413;16849.157542122844;16878.57803554921;16885;16887.408940687263;12913.741725165444;13032.41354139066;13041.021923355785;13056.020798440155;13058.804117938238;17015.659686806193;19280.94335775307;19425.619568396916;19456.99560075065;19490.11767280392;6742.157230346207;8081.6363263653275;8107.635156417975;8125.878111828328;8231.942014496375;891.8840550728364;1064.387976913278;1074.8817630060687;1131.6491887514896;1134.7843909657138;27123.728762712515;28109.578356324673;30331.848340758155;30347.93782217656;30777.384452310827;1041.3335465302212;1076.5962763963514;1099.1316859680921;1142.1833389126814;1142.5772464188303;15343.693126137414;15775.447859324868;15820.92089539568;15839.287232074585;16284.26720797567;670219;676944.6152769205;676945;677168;677997;51148.74425627848;59445;59884.401155987885;60112.39887601068;60564;1385.4319728911098;1406.6694326833185;1439.503371336887;1440.4382290906765;1442.343733601209;30974.690253097182;31766.205844854063;31851.68148318487;32489.837550812095;32671.509927350635;50845.49154508408;51376.74311628418;51442.22836657528;51460;51490;632172.6782732113;892555;922157;922738;923466;43225.35161972636;43618;43634.34548481839;44212.77893610511;44326.11347772962;47498.52501475079;47862.28206576835;47927.5207247923;48386.75806620944;48642;34265.828670856485;38479.807600961816;38747;39457.80271098626;40484.987875303355;38232.426513601764;38550.6144938547;38719.419208712454;39369.80315098406;39533.80233098931;37854.053648659006;38628.841134766;38813;38855.222895542494;39221.01947451222;31333;32661.510077348383;33864.49203261904;34135.487967680005;34841.47737783986;20549.79450205479;25002.249932502054;25121.371965701004;25559.23322300334;25685.3578660535 03:26:16 INFO - PROCESS | 2248 | |2;jquery.html;356.89650001499564;378.86171547385203;382.2145491015963;387.7031330200479;411.9382092686097;15564.377424903005;15566.221688915552;21098.156073757033;21224.57550848982;21499.785002149973;17482.56293592661;21466;21705.69765814052;21754.782452175514;22186.445338866535;5970.970145149268;6019.608725432855;6168.629882207059;6254.781082662108;6292.905606415908;4239.7668128252935;6121.969390153043;6239.064140378957;6250.374962503759;6598.142241508597;6824.727010919557;6899.344562266577;6918.65406729664;6919.342662447059;6960.234374218832;5722.828315150552;6735.494837887153;6832.350926661959;6841.3842754151865;6851.45188384928;5694.77220911163;5922.7927022554;5932.2584676915385;5937.495312898409;5945.672987985659;23097.653535196987;23216.7678323218;23361.532769344518;26384.60423093655;26753.331166720793;2310.803581695558;2369.040538581877;2471.357447063758;2522.7729504344516;2936.163193489845;15790.210489475481;15951.680966380723;16064.196790160562;16153.596160095974;16383.344666213456;1690.484402257311;1849.6256353723347;1869.6541139889164;1922.5097600112022;1947.6494231038407;118948;121357.17964230543;123733;123858.38070809678;125214;8477;10629.25595208328;11179.273347232403;11192.888071119185;11386.94306528462;11475.31148131098;13911.373988170557;13923.930380348034;13937.372818223204;13965;8479.830403392021;8771.16673915977;8793.868091978626;8883.644654213758;8894.199522043047;6755.898661520081;7251.56490610565;8846.557672116369;8857.867131993025;9055.909440905507;6587.143671322698;6727.389733181506;6730.259671436138;6734.966325168441;6857.622830744357;1600.6718622682322;1816.3551939061706;1827.241694696686;1855.2486243071578;1858.0709645177412;937.8171256605033;950.2398081534744;950.3110245072268;1042.5308611124872;1096.1669131460148;22494.66258006131;22645.18643627386;22661.433464163198;23053;23126.65310020351;972.8200282947536;975.9463229522303;977.3940157102642;1025.47700672656;1033.7777377863795;11263.324200547993;11428.771424571461;11863.34751588671;11909.987651049758;12060.819087713691;516877;525900;540251;560945;563164;64493.67753161204;94635;120599.39700301443;121551;123025;959.3428501476511;991.5587563534302;1015.2385710716962;1016.1870503597092;1050.1021626509378;19753.604927901648;19975.20099196016;20095.397138085882;20171.697424538936;20184.990750462126;26699.065532706263;37256.81371593125;52671.73664131655;53000.73499632477;53850.461495384545;72279.63860180666;72385;72398;74000.62999684968;75415.62292188503;7974.561399123104;8078.394120440873;8168.428210025241;8222.259996600334;8233.752987410388;13481.460741570227;13599.456021759019;13737.048946052368;13873.930630346784;13876.167429954236;11420.657380278604;11471.246900743594;11776.823347649963;11916.702082448008;12017.579384721494;10585.576576937145;11180.552777888794;11327.376994265394;11372.772544548898;11740.882591173979;9900.504974751379;11805.173637845268;11814.940925295663;11935;12145.210561313486;8022.5960456920975;8060.1133875274845;8418.200270974248;8445.66217351299;8485.745427637181;11314.660560183207;11339.206255562032;11654.30074195551;11656.825147622962;11711.531538738354 03:26:16 INFO - PROCESS | 2248 | |3;mootools.html;1382.9101108427944;1540.3376548084327;1574.4568123997221;1779.4216879514154;1931.652302585534;8084.2724154826055;8875.822483550324;9126.634934602607;9149.634014639407;9281.535923203846;8987.236084932789;9081.409708368968;9087.136722011415;9094.272458203328;9103.408278461895;2298.528801595677;2328.8951997160084;2330.8951097200666;2334.3347146063375;2335.544568809083;2349.765023497645;2382.1305223593436;2400.2919138853995;2400.7359190489033;2403.627437747143;2366.763323667628;2380.9047638094457;2389.761023897605;2391.868447235401;2399.2682231919257;2341.6487526870937;2353.8823058847074;2359.4927090675587;2371.4901296221224;2382.29722231942;2323.802476789475;2350.776676215774;2356.36378177891;2356.599378105726;2362.326736879998;8996.370254082216;9143.1771140597;9154.633814647406;9161.9921808601;9167.12912273333;5271.762770675329;5478.808241711521;5495.2856128703415;5518.117101263779;5526.613137080406;3129.890453834105;3604.5854726706502;3621.746477746559;3688.428293614494;3696.5194524711883;1051.6056478820442;1056.788642271541;1057.931234469757;1073.0664322039797;1078.9298695584841;12030.977366923822;12032.759344813056;12111.212771169845;12157.270563766202;12180.208286461264;4446.399736035659;4483.708558943658;4676.812927482931;4677.368555245066;4678.2982552616695;9467.337286390024;9468.857967130498;9475;9479.47862867549;9479.905200947902;5311.920321195185;5340.25236466895;5348.224507446476;5348.358197016383;5355.758990845421;4334.588214119716;4350.369196466495;4382.780860956939;4527.479339875957;4996.4254110777;3814.6185381462187;3840.7887566183567;3852.3836186210074;3892.1826416452295;3917.177392747498;992.5781542844406;1144.5536240865902;1160.468181999769;1163.598558497317;1173.049829637979;834.5368320582014;881.3345923827598;883.9745894762174;930.4231376546585;932.00275704995;5962.433568810956;6125.234345706786;6187.16473276111;6263.841189379979;6301.558890877594;421.13666982685373;430.4017415792052;454.56816024776595;456.1902622844478;457.76196377883315;3752.1932784451474;4041.1109555897656;4088.3254263046265;4176.686748493875;4182.64447521964;10747.247692661438;11310.151738619472;11325.263857849213;11331.886681133083;11336.489857956412;11243.325400475835;11259.493322800494;11274.323540587591;11291.983721465107;11333.94333028346;7957.761267161879;8309.750707478785;8451.323894088458;8577.528235946958;8587.270082043096;5309.867253318544;6097.695115244309;6132.754689812536;6272.749090036347;6291.874162516816;10018.39889606626;10420.478976051016;10516.68449946503;10531.104856087319;10544.05103540685;9625.855612165964;10721.463926803784;10721.839172412265;10733.302335348177;10752;7261.782146535613;7481.139668938142;7489.812754681177;7489.850202995801;7520.285572870568;7315.341619254293;8077.878831817645;8647.95676021641;8716.825663486821;8738.956305218433;7655.808604784925;7721.961390193013;7730.574818384818;7744.883826742491;7827.530348179127;7559.508631938907;7604.315611594983;7607.809804754926;7617.42869284817;7657.157712651715;7102.715891364288;7276.090488688914;7276.308750668678;7277.126744790659;7281.381082608038;5113.565346945551;5146.356705411824;5194.7922083116255;5230.6846851348555;5277.498637629419;5201.375834899836;5208.453112423293;5208.760233099803;5214.191800270964;5412.079946409199 03:26:16 INFO - PROCESS | 2248 | |4;prototype.html;300.49666808096435;300.78493876878025;314.6208818373859;319.872051179528;322.71278562079755;4408.779561021948;4704.600108990731;5054.115529782287;5485.561155107592;5504.339479262494;4261.872143835682;5056.671316364434;5204.970061675949;5304.904562697732;5354.8219391733855;4144.896377590561;4205.726627769185;4212.262854000553;4217.240896638604;4233.597808208216;4206.516250631171;4216.009237829107;4216.156768646268;4222.514410842762;4229.767362795045;3256.7557433192487;3586.7668601540818;3988.7008474364393;4094.6928980326447;4165.187788381267;4042.312806822847;4087.2847251730827;4089.4683691120263;4091.672666186728;4107.815148318333;3508.947315805274;3512.6838584527513;3521.3485505181625;3537.3809583323073;3538.2569660371355;5014.398272207322;5266.578673706094;5280.841574752763;5309.424928909476;5316.4683531646915;4346.630536404409;4450.732956022616;4668.762777863869;4688.132695451352;4703.106409782131;5249.370075590953;5589.776408943678;5741.555066959832;5755.280589926259;5762.942370576325;3747.887563373103;3781.149241420658;3917.196974620196;3946.9013274668077;3949.447077409165;5050.39395272566;5223.955208958224;5527.2538207342295;6030.10051833671;6123.571350005502;3540.433530635086;3764.435334699817;3960.7029472789654;3973.2450834341385;3996.421413541693;4020.4320315077143;4049.493813273341;4061.4517040199785;4061.9641991292033;4066.1054567994993;4584.4498660161;4672.415948006499;4695.060987802453;4732.574068333797;4743.430788305356;5725.2843394575675;5772.538196944234;5775.797847075332;5778;5797.492651910449;1237.8452693413324;1289.9551363395678;1308.188922867809;1364.7270545890863;1385.0650810702734;696.5611664651237;761.4593638516604;859.4070091636751;869.1091631078195;882.6142955559837;4607.594683621528;4859.962523422861;5104.41299250591;5333.57331413486;5381.67709937405;4654;5083.8983220335385;5215.843524694265;5624.409437009137;5706.657600543981;4423.557644235615;5045.697258164522;5341.252224688546;5369.704666243394;5505.916686655486;3108.2540190354152;3124.7968882022597;3129.3741251749743;3134.2634480896972;3150.779445438797;6118.20463339763;6138.938610613926;6140.956037473551;6143.662098584531;6146.969265153645;4590.42619672541;6113.755449781959;6122.387761223931;6139.109829074849;6156.107364432132;6137.386261373917;6149.0161574148815;6153.077038444269;6170.069217121921;6277.246730392382;5629.6903670298525;5752.5705206991215;5763.904858076952;5960.374160713236;5963.403659634176;5823.70881455934;5844.853878653068;5846.590738648254;5863.885861686266;5892.970535147297;5073.644844860824;5114.820981265638;6110.388961103854;6184.113845277234;6225.3774622537385;3530.91172720684;3953;3968.960310396859;3996.980015099906;4014.6587540059422;5615.410381909841;5947.1079338099635;5975.40245975399;6032.306284777307;6039.305479869871;5305.65513241638;5785.884282314414;5790.218320526675;5796.76812927495;5823.621464604787;4871.585915197106;5479.0959491683425;5677.347105082803;5769.221160140115;5774;4902.60779137661;5569.526590239713;5589.916151257653;5629.943700562942;5654.519365853784;4786.545278198566;5330.413654498079;5727.541796656341;5745;5752;5231.346081739783;5423.566114710892;5532.28080349552;5619.213310136666;5660.839283126981 03:26:16 INFO - PROCESS | 2248 | |5;yui.html;713.6681443128945;763.7403282883822;780.3679019993804;804.5735760047173;823.9133895876453;15002.699946001087;15916;16401.589960250985;16452.259648315823;16455.506334809976;14855.405783768636;14938.477153299636;15127.168005759706;15133.167675777828;15333.31000104998;686.7562015484506;854.9829003419928;877.4559772940786;878.2096113497839;878.8813510176109;706.3642721550619;853.5732133933034;887.1261807119981;894.208625366552;894.3337213775714;933.2067742418924;937.8218138553653;938.6245501799301;938.7887725261795;939.1922946266205;729.2561587181099;934.425328423015;935.9719208423792;936.1527817325332;936.2837429366506;662.4005275225928;684.9075374824434;687.6492988575812;688.7933619914056;690.977353516793;15361.539153825403;15383.230838458145;15396.307166177548;15404.922975385163;15412.998155119882;13981.440742370292;13985.650358741012;14115.647108822259;14276.14343139415;14468.565943021726;12985.545505907343;13099.934500327532;13113.147645403114;13323.800142997863;13362.398692058881;3829.923401531954;4607.493175750665;4623.74843943515;4687.273472611751;4717.622590192776;107116.99999999921;110403.44798275958;110798;110808;110850.44574777155;10967.396793176453;11144.387058711849;11161.888381116247;11245.94377028126;11772.234804737665;22498.775012249997;22505.212317568807;22519.211827586285;22522.436939076328;22573.322800315687;7848.13670496245;8009.759707208793;8063.5968201589685;8150.51096934174;8188.7952801180445;4289.0027474567705;4387.758673272937;4469.7318160910445;4494.07871386365;4594.609458196024;3517.2262102337445;3620.5293311869286;3636.509071275344;3641.253543023674;3648.3980143276594;594.8364199845078;625.2590680044162;643.6459947029209;654.8101050695341;673.0308355967392;570.4267211452546;631.6336524815688;634.8793729191439;645.4191227894933;650.8340373204898;8584.527850968132;9061.275097992146;9547.379420337795;10126.139278161292;10431.68704938853;521.1765410651162;585.1895125251557;638.9361712748222;648.1832890557837;686.4782765098461;4480.484744254387;4790.329353890458;4862.41651001882;4866.586340161055;4886.633502487328;87066.56466717625;121788;133617.33191333982;145856.5414345843;158674;79461.20538794654;112287;116343;117725.41137294259;125245.37377313054;107627;108148.4592577032;108622.45688771663;109305.45347273213;114176.4291178572;8473.66105355779;9189.494577798017;9299.256059515357;9306.92970308396;9411;53237.7338113307;54427.45572544224;56020.159697603754;56263.71868140633;58906.41093589009;88947;90802.54598727229;91047.54476227841;92083;94714.52642736743;13147;13388.397522111723;13439;13832.308384580529;14122.929385353007;14308.713825723635;14309.64225894361;14612.123272603678;14652.402676247075;14733.92633036828;13530.200009925895;14026.649333766738;14031.508897188549;14404.495842645458;14741.262936853329;12049.759004820029;13714.17714937107;14278.857211427752;14735.115893046452;14814.703705925605;11575.42122893835;13656.251557283022;13862.722745545234;14294.785578216542;14616.561503154922;11453.541858325907;11468.598599048993;11499.435175095747;11731.648050558155;11903.40482975865;8590.226879580869;9134.148516617515;11078.46164573235;11360;11655.358955257543 03:26:16 INFO - PROCESS | 2248 | __end_tp_report 03:26:16 INFO - PROCESS | 2248 | __start_cc_report 03:26:16 INFO - PROCESS | 2248 | _x_x_mozilla_cycle_collect,1449 03:26:16 INFO - PROCESS | 2248 | __end_cc_report 03:26:16 INFO - PROCESS | 2248 | __startTimestamp1469874376545__endTimestamp 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | ------- Summary: start ------- 03:26:16 INFO - PROCESS | 2248 | Number of tests: 6 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#0] dojo.html Cycles:165 Average:5712.48 Median:5918.97 stddev:673.38 (11.4%) stddev-sans-first:647.33 03:26:16 INFO - PROCESS | 2248 | Values: 3258.3 3264.7 3286.0 3304.7 3327.6 5476.9 5518.1 5598.0 5599.3 5614.3 5582.9 5617.9 5636.5 5662.0 5668.0 5100.3 5120.1 5156.4 5190.1 5214.9 5200.9 5226.6 5232.8 5237.6 5248.1 5149.1 5160.7 5170.1 5175.8 5235.9 5226.0 5267.9 5275.4 5281.3 5331.6 5028.7 5040.3 5086.5 5164.9 5175.9 4062.8 5463.4 5478.2 5612.4 5622.2 6246.5 6249.3 6251.0 6259.8 6269.3 6129.7 6164.6 6278.7 6306.6 6307.5 5803.4 5822.8 5830.7 5832.4 5841.3 6314.1 6325.2 6327.1 6341.8 6352.3 5965.1 6018.7 6070.6 6155.3 6203.6 6257.2 6299.5 6313.2 6318.1 6319.6 6163.2 6221.6 6233.2 6239.6 6274.4 6086.7 6108.3 6206.5 6279.2 6288.3 4879.8 4892.7 4895.3 4896.3 4896.6 4350.9 4413.6 4444.4 4449.2 4450.1 5676.6 5685.5 5739.5 5742.8 5745.2 6249.3 6272.1 6294.1 6299.5 6323.4 5578.0 5669.9 5675.6 5677.7 5686.1 4513.2 5338.2 5394.9 5408.2 5411.0 6218.0 6244.5 6265.8 6282.2 6301.4 6284.7 6291.9 6292.5 6294.5 6321.6 6272.3 6279.8 6295.6 6296.6 6297.0 6115.9 6121.2 6140.8 6220.6 6235.4 6271.2 6289.8 6295.2 6298.2 6300.1 5905.5 6031.7 6069.8 6110.2 6134.8 5949.5 5952.4 6039.4 6045.7 6104.1 5911.7 5919.0 5957.2 5958.9 5982.8 6098.6 6125.8 6134.4 6135.9 6137.3 5676.4 5704.0 5750.7 5806.4 5811.7 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#1] ext.html Cycles:180 Average:80000.26 Median:36039.91 stddev:190222.55 (527.8%) stddev-sans-first:190662.37 03:26:16 INFO - PROCESS | 2248 | Values: 439.3 534.3 538.8 539.3 552.0 23876.0 26021.8 30333.5 30526.2 32041.2 43011.6 43918.0 43955.3 46380.8 46501.0 8779.5 9238.5 9650.5 9785.2 9843.6 7583.9 8812.6 9000.1 9599.4 9709.0 9038.6 9099.3 11644.4 11835.3 11900.3 9917.4 12015.3 12027.2 12027.9 12037.6 11722.9 11803.8 11803.8 11810.2 11822.7 32486.8 32745.8 32785.7 34136.8 40775.6 3409.6 3513.4 3692.2 3853.1 3923.3 31559.5 31701.5 32590.0 32704.7 32908.7 3182.1 3205.2 3231.5 3258.6 3354.1 534562.3 536636.0 537610.3 539167.0 539231.0 59452.8 59684.4 59920.0 59970.0 60077.0 34915.1 35220.6 35456.8 36623.0 36718.0 16689.9 16849.2 16878.6 16885.0 16887.4 12913.7 13032.4 13041.0 13056.0 13058.8 17015.7 19280.9 19425.6 19457.0 19490.1 6742.2 8081.6 8107.6 8125.9 8231.9 891.9 1064.4 1074.9 1131.6 1134.8 27123.7 28109.6 30331.8 30347.9 30777.4 1041.3 1076.6 1099.1 1142.2 1142.6 15343.7 15775.4 15820.9 15839.3 16284.3 670219.0 676944.6 676945.0 677168.0 677997.0 51148.7 59445.0 59884.4 60112.4 60564.0 1385.4 1406.7 1439.5 1440.4 1442.3 30974.7 31766.2 31851.7 32489.8 32671.5 50845.5 51376.7 51442.2 51460.0 51490.0 632172.7 892555.0 922157.0 922738.0 923466.0 43225.4 43618.0 43634.3 44212.8 44326.1 47498.5 47862.3 47927.5 48386.8 48642.0 34265.8 38479.8 38747.0 39457.8 40485.0 38232.4 38550.6 38719.4 39369.8 39533.8 37854.1 38628.8 38813.0 38855.2 39221.0 31333.0 32661.5 33864.5 34135.5 34841.5 20549.8 25002.2 25121.4 25559.2 25685.4 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#2] jquery.html Cycles:180 Average:32965.72 Median:23289.15 stddev:90419.72 (388.2%) stddev-sans-first:90640.22 03:26:16 INFO - PROCESS | 2248 | Values: 356.9 378.9 382.2 387.7 411.9 15564.4 15566.2 21098.2 21224.6 21499.8 17482.6 21466.0 21705.7 21754.8 22186.4 5971.0 6019.6 6168.6 6254.8 6292.9 4239.8 6122.0 6239.1 6250.4 6598.1 6824.7 6899.3 6918.7 6919.3 6960.2 5722.8 6735.5 6832.4 6841.4 6851.5 5694.8 5922.8 5932.3 5937.5 5945.7 23097.7 23216.8 23361.5 26384.6 26753.3 2310.8 2369.0 2471.4 2522.8 2936.2 15790.2 15951.7 16064.2 16153.6 16383.3 1690.5 1849.6 1869.7 1922.5 1947.6 118948.0 121357.2 123733.0 123858.4 125214.0 8477.0 10629.3 11179.3 11192.9 11386.9 11475.3 13911.4 13923.9 13937.4 13965.0 8479.8 8771.2 8793.9 8883.6 8894.2 6755.9 7251.6 8846.6 8857.9 9055.9 6587.1 6727.4 6730.3 6735.0 6857.6 1600.7 1816.4 1827.2 1855.2 1858.1 937.8 950.2 950.3 1042.5 1096.2 22494.7 22645.2 22661.4 23053.0 23126.7 972.8 975.9 977.4 1025.5 1033.8 11263.3 11428.8 11863.3 11910.0 12060.8 516877.0 525900.0 540251.0 560945.0 563164.0 64493.7 94635.0 120599.4 121551.0 123025.0 959.3 991.6 1015.2 1016.2 1050.1 19753.6 19975.2 20095.4 20171.7 20185.0 26699.1 37256.8 52671.7 53000.7 53850.5 72279.6 72385.0 72398.0 74000.6 75415.6 7974.6 8078.4 8168.4 8222.3 8233.8 13481.5 13599.5 13737.0 13873.9 13876.2 11420.7 11471.2 11776.8 11916.7 12017.6 10585.6 11180.6 11327.4 11372.8 11740.9 9900.5 11805.2 11814.9 11935.0 12145.2 8022.6 8060.1 8418.2 8445.7 8485.7 11314.7 11339.2 11654.3 11656.8 11711.5 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#3] mootools.html Cycles:180 Average:5827.10 Median:4677.83 stddev:3354.39 (71.7%) stddev-sans-first:3347.18 03:26:16 INFO - PROCESS | 2248 | Values: 1382.9 1540.3 1574.5 1779.4 1931.7 8084.3 8875.8 9126.6 9149.6 9281.5 8987.2 9081.4 9087.1 9094.3 9103.4 2298.5 2328.9 2330.9 2334.3 2335.5 2349.8 2382.1 2400.3 2400.7 2403.6 2366.8 2380.9 2389.8 2391.9 2399.3 2341.6 2353.9 2359.5 2371.5 2382.3 2323.8 2350.8 2356.4 2356.6 2362.3 8996.4 9143.2 9154.6 9162.0 9167.1 5271.8 5478.8 5495.3 5518.1 5526.6 3129.9 3604.6 3621.7 3688.4 3696.5 1051.6 1056.8 1057.9 1073.1 1078.9 12031.0 12032.8 12111.2 12157.3 12180.2 4446.4 4483.7 4676.8 4677.4 4678.3 9467.3 9468.9 9475.0 9479.5 9479.9 5311.9 5340.3 5348.2 5348.4 5355.8 4334.6 4350.4 4382.8 4527.5 4996.4 3814.6 3840.8 3852.4 3892.2 3917.2 992.6 1144.6 1160.5 1163.6 1173.0 834.5 881.3 884.0 930.4 932.0 5962.4 6125.2 6187.2 6263.8 6301.6 421.1 430.4 454.6 456.2 457.8 3752.2 4041.1 4088.3 4176.7 4182.6 10747.2 11310.2 11325.3 11331.9 11336.5 11243.3 11259.5 11274.3 11292.0 11333.9 7957.8 8309.8 8451.3 8577.5 8587.3 5309.9 6097.7 6132.8 6272.7 6291.9 10018.4 10420.5 10516.7 10531.1 10544.1 9625.9 10721.5 10721.8 10733.3 10752.0 7261.8 7481.1 7489.8 7489.9 7520.3 7315.3 8077.9 8648.0 8716.8 8739.0 7655.8 7722.0 7730.6 7744.9 7827.5 7559.5 7604.3 7607.8 7617.4 7657.2 7102.7 7276.1 7276.3 7277.1 7281.4 5113.6 5146.4 5194.8 5230.7 5277.5 5201.4 5208.5 5208.8 5214.2 5412.1 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#4] prototype.html Cycles:180 Average:4644.21 Median:5210.41 stddev:1438.14 (27.6%) stddev-sans-first:1404.73 03:26:16 INFO - PROCESS | 2248 | Values: 300.5 300.8 314.6 319.9 322.7 4408.8 4704.6 5054.1 5485.6 5504.3 4261.9 5056.7 5205.0 5304.9 5354.8 4144.9 4205.7 4212.3 4217.2 4233.6 4206.5 4216.0 4216.2 4222.5 4229.8 3256.8 3586.8 3988.7 4094.7 4165.2 4042.3 4087.3 4089.5 4091.7 4107.8 3508.9 3512.7 3521.3 3537.4 3538.3 5014.4 5266.6 5280.8 5309.4 5316.5 4346.6 4450.7 4668.8 4688.1 4703.1 5249.4 5589.8 5741.6 5755.3 5762.9 3747.9 3781.1 3917.2 3946.9 3949.4 5050.4 5224.0 5527.3 6030.1 6123.6 3540.4 3764.4 3960.7 3973.2 3996.4 4020.4 4049.5 4061.5 4062.0 4066.1 4584.4 4672.4 4695.1 4732.6 4743.4 5725.3 5772.5 5775.8 5778.0 5797.5 1237.8 1290.0 1308.2 1364.7 1385.1 696.6 761.5 859.4 869.1 882.6 4607.6 4860.0 5104.4 5333.6 5381.7 4654.0 5083.9 5215.8 5624.4 5706.7 4423.6 5045.7 5341.3 5369.7 5505.9 3108.3 3124.8 3129.4 3134.3 3150.8 6118.2 6138.9 6141.0 6143.7 6147.0 4590.4 6113.8 6122.4 6139.1 6156.1 6137.4 6149.0 6153.1 6170.1 6277.2 5629.7 5752.6 5763.9 5960.4 5963.4 5823.7 5844.9 5846.6 5863.9 5893.0 5073.6 5114.8 6110.4 6184.1 6225.4 3530.9 3953.0 3969.0 3997.0 4014.7 5615.4 5947.1 5975.4 6032.3 6039.3 5305.7 5785.9 5790.2 5796.8 5823.6 4871.6 5479.1 5677.3 5769.2 5774.0 4902.6 5569.5 5589.9 5629.9 5654.5 4786.5 5330.4 5727.5 5745.0 5752.0 5231.3 5423.6 5532.3 5619.2 5660.8 03:26:16 INFO - PROCESS | 2248 | 03:26:16 INFO - PROCESS | 2248 | [#5] yui.html Cycles:180 Average:23799.69 Median:3638.88 stddev:36705.33 (1008.7%) stddev-sans-first:36767.37 03:26:16 INFO - PROCESS | 2248 | Values: 713.7 763.7 780.4 804.6 823.9 15002.7 15916.0 16401.6 16452.3 16455.5 14855.4 14938.5 15127.2 15133.2 15333.3 686.8 855.0 877.5 878.2 878.9 706.4 853.6 887.1 894.2 894.3 933.2 937.8 938.6 938.8 939.2 729.3 934.4 936.0 936.2 936.3 662.4 684.9 687.6 688.8 691.0 15361.5 15383.2 15396.3 15404.9 15413.0 13981.4 13985.7 14115.6 14276.1 14468.6 12985.5 13099.9 13113.1 13323.8 13362.4 3829.9 4607.5 4623.7 4687.3 4717.6 107117.0 110403.4 110798.0 110808.0 110850.4 10967.4 11144.4 11161.9 11245.9 11772.2 22498.8 22505.2 22519.2 22522.4 22573.3 7848.1 8009.8 8063.6 8150.5 8188.8 4289.0 4387.8 4469.7 4494.1 4594.6 3517.2 3620.5 3636.5 3641.3 3648.4 594.8 625.3 643.6 654.8 673.0 570.4 631.6 634.9 645.4 650.8 8584.5 9061.3 9547.4 10126.1 10431.7 521.2 585.2 638.9 648.2 686.5 4480.5 4790.3 4862.4 4866.6 4886.6 87066.6 121788.0 133617.3 145856.5 158674.0 79461.2 112287.0 116343.0 117725.4 125245.4 107627.0 108148.5 108622.5 109305.5 114176.4 8473.7 9189.5 9299.3 9306.9 9411.0 53237.7 54427.5 56020.2 56263.7 58906.4 88947.0 90802.5 91047.5 92083.0 94714.5 13147.0 13388.4 13439.0 13832.3 14122.9 14308.7 14309.6 14612.1 14652.4 14733.9 13530.2 14026.6 14031.5 14404.5 14741.3 12049.8 13714.2 14278.9 14735.1 14814.7 11575.4 13656.3 13862.7 14294.8 14616.6 11453.5 11468.6 11499.4 11731.6 11903.4 8590.2 9134.1 11078.5 11360.0 11655.4 03:26:16 INFO - PROCESS | 2248 | -------- Summary: end -------- 03:26:16 INFO - PROCESS | 2248 | 03:26:17 INFO - TEST-INFO | 2248: exit 0 03:26:17 INFO - TEST-OK | dromaeo_css | took 1117493ms 03:26:17 INFO - TEST-START | kraken 03:26:17 INFO - operating with platform_type : mac_ 03:26:17 INFO - Initialising browser for kraken test... 03:26:17 INFO - TEST-INFO | started process 2253 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp1wgUwP/profile http://localhost:58594/getInfo.html) 03:26:19 INFO - PROCESS | 2253 | __metrics Screen width/height:1600/1200 03:26:19 INFO - PROCESS | 2253 | colorDepth:24 03:26:19 INFO - PROCESS | 2253 | Browser inner width/height: 1024/689 03:26:19 INFO - PROCESS | 2253 | __metrics 03:26:19 INFO - PROCESS | 2253 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:26:19 INFO - PROCESS | 2253 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:26:20 INFO - TEST-INFO | 2253: exit 0 03:26:20 INFO - Browser initialized. 03:26:20 INFO - Running cycle 1/1 for kraken test... 03:26:20 INFO - TEST-INFO | started process 2254 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp1wgUwP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:32 INFO - PROCESS | 2254 | RSS: Main: 243703808 03:26:32 INFO - PROCESS | 2254 | 03:26:34 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58594/tests/kraken/driver.html?testName=audio-beat-detection) 03:26:35 INFO - PROCESS | 2254 | RSS: Main: 324239360 03:26:35 INFO - PROCESS | 2254 | 03:26:38 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58594/tests/kraken/driver.html?testName=audio-dft) 03:26:39 INFO - PROCESS | 2254 | RSS: Main: 331206656 03:26:39 INFO - PROCESS | 2254 | 03:26:42 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58594/tests/kraken/driver.html?testName=audio-fft) 03:26:43 INFO - PROCESS | 2254 | RSS: Main: 427888640 03:26:43 INFO - PROCESS | 2254 | 03:26:45 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58594/tests/kraken/driver.html?testName=audio-oscillator) 03:26:45 INFO - PROCESS | 2254 | RSS: Main: 329863168 03:26:45 INFO - PROCESS | 2254 | 03:26:47 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58594/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:26:48 INFO - PROCESS | 2254 | RSS: Main: 335552512 03:26:48 INFO - PROCESS | 2254 | 03:26:51 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58594/tests/kraken/driver.html?testName=imaging-darkroom) 03:26:51 INFO - PROCESS | 2254 | RSS: Main: 371204096 03:26:51 INFO - PROCESS | 2254 | 03:26:54 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58594/tests/kraken/driver.html?testName=imaging-desaturate) 03:26:55 INFO - PROCESS | 2254 | RSS: Main: 375078912 03:26:55 INFO - PROCESS | 2254 | 03:26:58 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58594/tests/kraken/driver.html?testName=json-parse-financial) 03:26:59 INFO - PROCESS | 2254 | RSS: Main: 371265536 03:26:59 INFO - PROCESS | 2254 | 03:27:00 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58594/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:27:01 INFO - PROCESS | 2254 | RSS: Main: 344363008 03:27:01 INFO - PROCESS | 2254 | 03:27:03 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:27:03 INFO - PROCESS | 2254 | RSS: Main: 350654464 03:27:03 INFO - PROCESS | 2254 | 03:27:05 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:27:06 INFO - PROCESS | 2254 | RSS: Main: 358838272 03:27:06 INFO - PROCESS | 2254 | 03:27:09 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:27:10 INFO - PROCESS | 2254 | RSS: Main: 363147264 03:27:10 INFO - PROCESS | 2254 | 03:27:13 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:27:13 INFO - PROCESS | 2254 | RSS: Main: 364064768 03:27:13 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | Cycle 1(1): loaded http://localhost:58594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58594/tests/kraken/driver.html?testName=ai-astar) 03:27:15 INFO - PROCESS | 2254 | RSS: Main: 407678976 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | __start_tp_report 03:27:15 INFO - PROCESS | 2254 | _x_x_mozilla_page_load 03:27:15 INFO - PROCESS | 2254 | _x_x_mozilla_page_load_details 03:27:15 INFO - PROCESS | 2254 | |i|pagename|runs| 03:27:15 INFO - PROCESS | 2254 | |0;ai-astar;101;113;114;113;113;111;114;112;112;113 03:27:15 INFO - PROCESS | 2254 | |1;audio-beat-detection;140;107;110;194;88;126;163;137;142;106 03:27:15 INFO - PROCESS | 2254 | |2;audio-dft;187;184;180;184;183;182;184;182;188;182 03:27:15 INFO - PROCESS | 2254 | |3;audio-fft;78;59;57;107;57;57;61;61;85;57 03:27:15 INFO - PROCESS | 2254 | |4;audio-oscillator;82;67;92;71;74;68;95;70;67;100 03:27:15 INFO - PROCESS | 2254 | |5;imaging-gaussian-blur;88;87;89;87;89;86;90;86;88;100 03:27:15 INFO - PROCESS | 2254 | |6;imaging-darkroom;112;149;150;95;96;95;110;149;119;94 03:27:15 INFO - PROCESS | 2254 | |7;imaging-desaturate;82;84;84;85;84;86;84;84;85;82 03:27:15 INFO - PROCESS | 2254 | |8;json-parse-financial;54;51;51;54;53;53;53;53;53;54 03:27:15 INFO - PROCESS | 2254 | |9;json-stringify-tinderbox;67;68;77;83;78;74;73;72;73;72 03:27:15 INFO - PROCESS | 2254 | |10;stanford-crypto-aes;85;86;87;85;85;87;82;81;84;119 03:27:15 INFO - PROCESS | 2254 | |11;stanford-crypto-ccm;135;137;151;148;139;136;135;137;156;158 03:27:15 INFO - PROCESS | 2254 | |12;stanford-crypto-pbkdf2;166;221;223;165;166;165;165;165;165;164 03:27:15 INFO - PROCESS | 2254 | |13;stanford-crypto-sha256-iterative;68;66;67;67;66;66;66;65;66;66 03:27:15 INFO - PROCESS | 2254 | __end_tp_report 03:27:15 INFO - PROCESS | 2254 | __start_cc_report 03:27:15 INFO - PROCESS | 2254 | _x_x_mozilla_cycle_collect,2036 03:27:15 INFO - PROCESS | 2254 | __end_cc_report 03:27:15 INFO - PROCESS | 2254 | __startTimestamp1469874435687__endTimestamp 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | ------- Summary: start ------- 03:27:15 INFO - PROCESS | 2254 | Number of tests: 14 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.84 (3.4%) stddev-sans-first:0.97 03:27:15 INFO - PROCESS | 2254 | Values: 101.0 113.0 114.0 113.0 113.0 111.0 114.0 112.0 112.0 113.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:141.00 stddev:31.17 (22.1%) stddev-sans-first:32.91 03:27:15 INFO - PROCESS | 2254 | Values: 140.0 107.0 110.0 194.0 88.0 126.0 163.0 137.0 142.0 106.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:2.41 (1.3%) stddev-sans-first:2.22 03:27:15 INFO - PROCESS | 2254 | Values: 187.0 184.0 180.0 184.0 183.0 182.0 184.0 182.0 188.0 182.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#3] audio-fft Cycles:10 Average:67.90 Median:60.00 stddev:16.91 (28.2%) stddev-sans-first:17.53 03:27:15 INFO - PROCESS | 2254 | Values: 78.0 59.0 57.0 107.0 57.0 57.0 61.0 61.0 85.0 57.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:72.50 stddev:12.70 (17.5%) stddev-sans-first:13.41 03:27:15 INFO - PROCESS | 2254 | Values: 82.0 67.0 92.0 71.0 74.0 68.0 95.0 70.0 67.0 100.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:88.00 stddev:4.08 (4.6%) stddev-sans-first:4.31 03:27:15 INFO - PROCESS | 2254 | Values: 88.0 87.0 89.0 87.0 89.0 86.0 90.0 86.0 88.0 100.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#6] imaging-darkroom Cycles:10 Average:116.90 Median:122.00 stddev:23.93 (19.6%) stddev-sans-first:25.31 03:27:15 INFO - PROCESS | 2254 | Values: 112.0 149.0 150.0 95.0 96.0 95.0 110.0 149.0 119.0 94.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:1.25 (1.5%) stddev-sans-first:1.09 03:27:15 INFO - PROCESS | 2254 | Values: 82.0 84.0 84.0 85.0 84.0 86.0 84.0 84.0 85.0 82.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.10 (2.1%) stddev-sans-first:1.09 03:27:15 INFO - PROCESS | 2254 | Values: 54.0 51.0 51.0 54.0 53.0 53.0 53.0 53.0 53.0 54.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:73.50 stddev:4.72 (6.4%) stddev-sans-first:4.33 03:27:15 INFO - PROCESS | 2254 | Values: 67.0 68.0 77.0 83.0 78.0 74.0 73.0 72.0 73.0 72.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.00 stddev:11.03 (13.0%) stddev-sans-first:11.64 03:27:15 INFO - PROCESS | 2254 | Values: 85.0 86.0 87.0 85.0 85.0 87.0 82.0 81.0 84.0 119.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:143.50 stddev:9.11 (6.4%) stddev-sans-first:9.17 03:27:15 INFO - PROCESS | 2254 | Values: 135.0 137.0 151.0 148.0 139.0 136.0 135.0 137.0 156.0 158.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:165.50 stddev:23.99 (14.5%) stddev-sans-first:25.14 03:27:15 INFO - PROCESS | 2254 | Values: 166.0 221.0 223.0 165.0 166.0 165.0 165.0 165.0 165.0 164.0 03:27:15 INFO - PROCESS | 2254 | 03:27:15 INFO - PROCESS | 2254 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.60 03:27:15 INFO - PROCESS | 2254 | Values: 68.0 66.0 67.0 67.0 66.0 66.0 66.0 65.0 66.0 66.0 03:27:15 INFO - PROCESS | 2254 | -------- Summary: end -------- 03:27:15 INFO - PROCESS | 2254 | 03:27:16 INFO - TEST-INFO | 2254: exit 0 03:27:16 INFO - TEST-OK | kraken | took 59404ms 03:27:16 INFO - SUITE-END | took 1176s 03:27:16 INFO - Completed test suite (00:19:37) 03:27:16 INFO - javascript benchmark 03:27:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.266889949761, 3264.6735326467347, 3285.983570082149, 3304.7356211503084, 3327.584051993501, 5476.890462190754, 5518.117101263799, 5597.972010139949, 5599.300087489064, 5614.326280846294, 5582.888342233153, 5617.887642247163, 5636.549076073915, 5662.0, 5668.036433806253, 5100.33695619569, 5120.078385890538, 5156.381234251895, 5190.091733946563, 5214.947850521484, 5200.895982080356, 5226.581873450134, 5232.764525596357, 5237.633365664386, 5248.134057880445, 5149.124648809711, 5160.716160611165, 5170.121079416489, 5175.844724658247, 5235.921461178085, 5225.973870130663, 5267.947320526784, 5275.393329767049, 5281.287026251445, 5331.573474122061, 5028.698278103326, 5040.344755181803, 5086.46592107827, 5164.896702065976, 5175.870603234949, 4062.7562346259024, 5463.3717122530625, 5478.205660179252, 5612.410696876851, 5622.156676498517, 6246.531510136758, 6249.343818899041, 6251.0, 6259.812205633838, 6269.279032911182, 6129.69351532426, 6164.630122192638, 6278.717457714414, 6306.621602703853, 6307.463865571432, 5803.390643982406, 5822.767089316422, 5830.737616807254, 5832.387599302055, 5841.32824725162, 6314.0528920661345, 6325.209348831396, 6327.082573026885, 6341.778037768656, 6352.333005034498, 5965.075413310944, 6018.729157187937, 6070.605410648293, 6155.261368635793, 6203.627782333075, 6257.186565746424, 6299.464545513591, 6313.2424109107205, 6318.147050148171, 6319.58922670025, 6163.229596300463, 6221.564490485713, 6233.189685340877, 6239.563230573816, 6274.435300822856, 6086.726097325631, 6108.251008765869, 6206.534509911775, 6279.183706118175, 6288.339724328879, 4879.829205977774, 4892.730899800546, 4895.31465594817, 4896.314515967767, 4896.559309662148, 4350.891227719332, 4413.624841888475, 4444.377787109807, 4449.243628583149, 4450.109978004412, 5676.574256930747, 5685.5167310778215, 5739.454751798572, 5742.827715168552, 5745.195672605839, 6249.25008998923, 6272.1219029335925, 6294.055891616203, 6299.527535434769, 6323.367663233732, 5577.972110139423, 5669.858253543612, 5675.631083979528, 5677.659340439587, 5686.061799802987, 4513.210188217049, 5338.225957236256, 5394.9460505394445, 5408.188771684278, 5410.97294513525, 6217.9689101555105, 6244.469220116341, 6265.843353916189, 6282.183316168869, 6301.4328710416285, 6284.685765711788, 6291.937080629135, 6292.496600271876, 6294.464970477561, 6321.620702757849, 6272.310045894857, 6279.843003924939, 6295.622262664255, 6296.590721603081, 6297.0, 6115.908261375994, 6121.209103132385, 6140.785072522441, 6220.564560480723, 6235.376462353728, 6271.216097987752, 6289.842753931189, 6295.244570651551, 6298.212723409574, 6300.117983482408, 5905.4685078343155, 6031.728572214173, 6069.817905462844, 6110.175126357884, 6134.754609815557, 5949.464548190511, 5952.434518720715, 6039.396060393926, 6045.667488288186, 6104.06507294887, 5911.733971971357, 5918.970405147947, 5957.16599676037, 5958.880822383441, 5982.820515384546, 6098.566012856479, 6125.815438131279, 6134.356392119172, 6135.877282454237, 6137.3248942617165, 5676.4323567642905, 5703.971480142573, 5750.712464376848, 5806.4193580641595, 5811.680357580373], "value": 5667.404803404769, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.3146691161787, 534.3320848938827, 538.8329617818475, 539.344696194124, 552.039451354643, 23876.0, 26021.829017694177, 30333.544996825065, 30526.23684407891, 32041.19897002576, 43011.56988430115, 43918.0, 43955.34066988998, 46380.768096159474, 46501.0, 8779.473231606124, 9238.538073096352, 9650.517474126284, 9785.217182625409, 9843.557039933185, 7583.9241607584045, 8812.559372031374, 9000.144986226298, 9599.424034557915, 9708.95145524275, 9038.593263303133, 9099.272058235358, 11644.41777911101, 11835.289882607029, 11900.285982841056, 9917.404955702645, 12015.339156346397, 12027.158098933121, 12027.93986030073, 12037.638870833889, 11722.94138529302, 11803.763924721457, 11803.822942655781, 11810.173287869853, 11822.694574557965, 32486.83756581202, 32745.83627081873, 32785.6721432785, 34136.82931585351, 40775.59224407777, 3409.590849098099, 3513.367593833109, 3692.2061756722433, 3853.0560012796764, 3923.2545816294814, 31559.50881768793, 31701.52447713286, 32590.02229933082, 32704.672953270638, 32908.67091329104, 3182.138707082336, 3205.2307446212976, 3231.466807976681, 3258.625258095302, 3354.111160542458, 534562.3271883694, 536636.0, 537610.3119484417, 539167.0, 539231.0, 59452.81094378088, 59684.40315596875, 59920.0, 59970.0, 60077.0, 34915.12712182165, 35220.64779352173, 35456.822715886774, 36623.0, 36718.0, 16689.916550417413, 16849.157542122844, 16878.57803554921, 16885.0, 16887.408940687263, 12913.741725165444, 13032.41354139066, 13041.021923355785, 13056.020798440155, 13058.804117938238, 17015.659686806193, 19280.94335775307, 19425.619568396916, 19456.99560075065, 19490.11767280392, 6742.157230346207, 8081.6363263653275, 8107.635156417975, 8125.878111828328, 8231.942014496375, 891.8840550728364, 1064.387976913278, 1074.8817630060687, 1131.6491887514896, 1134.7843909657138, 27123.728762712515, 28109.578356324673, 30331.848340758155, 30347.93782217656, 30777.384452310827, 1041.3335465302212, 1076.5962763963514, 1099.1316859680921, 1142.1833389126814, 1142.5772464188303, 15343.693126137414, 15775.447859324868, 15820.92089539568, 15839.287232074585, 16284.26720797567, 670219.0, 676944.6152769205, 676945.0, 677168.0, 677997.0, 51148.74425627848, 59445.0, 59884.401155987885, 60112.39887601068, 60564.0, 1385.4319728911098, 1406.6694326833185, 1439.503371336887, 1440.4382290906765, 1442.343733601209, 30974.690253097182, 31766.205844854063, 31851.68148318487, 32489.837550812095, 32671.509927350635, 50845.49154508408, 51376.74311628418, 51442.22836657528, 51460.0, 51490.0, 632172.6782732113, 892555.0, 922157.0, 922738.0, 923466.0, 43225.35161972636, 43618.0, 43634.34548481839, 44212.77893610511, 44326.11347772962, 47498.52501475079, 47862.28206576835, 47927.5207247923, 48386.75806620944, 48642.0, 34265.828670856485, 38479.807600961816, 38747.0, 39457.80271098626, 40484.987875303355, 38232.426513601764, 38550.6144938547, 38719.419208712454, 39369.80315098406, 39533.80233098931, 37854.053648659006, 38628.841134766, 38813.0, 38855.222895542494, 39221.01947451222, 31333.0, 32661.510077348383, 33864.49203261904, 34135.487967680005, 34841.47737783986, 20549.79450205479, 25002.249932502054, 25121.371965701004, 25559.23322300334, 25685.3578660535], "value": 20524.079396043533, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.89650001499564, 378.86171547385203, 382.2145491015963, 387.7031330200479, 411.9382092686097, 15564.377424903005, 15566.221688915552, 21098.156073757033, 21224.57550848982, 21499.785002149973, 17482.56293592661, 21466.0, 21705.69765814052, 21754.782452175514, 22186.445338866535, 5970.970145149268, 6019.608725432855, 6168.629882207059, 6254.781082662108, 6292.905606415908, 4239.7668128252935, 6121.969390153043, 6239.064140378957, 6250.374962503759, 6598.142241508597, 6824.727010919557, 6899.344562266577, 6918.65406729664, 6919.342662447059, 6960.234374218832, 5722.828315150552, 6735.494837887153, 6832.350926661959, 6841.3842754151865, 6851.45188384928, 5694.77220911163, 5922.7927022554, 5932.2584676915385, 5937.495312898409, 5945.672987985659, 23097.653535196987, 23216.7678323218, 23361.532769344518, 26384.60423093655, 26753.331166720793, 2310.803581695558, 2369.040538581877, 2471.357447063758, 2522.7729504344516, 2936.163193489845, 15790.210489475481, 15951.680966380723, 16064.196790160562, 16153.596160095974, 16383.344666213456, 1690.484402257311, 1849.6256353723347, 1869.6541139889164, 1922.5097600112022, 1947.6494231038407, 118948.0, 121357.17964230543, 123733.0, 123858.38070809678, 125214.0, 8477.0, 10629.25595208328, 11179.273347232403, 11192.888071119185, 11386.94306528462, 11475.31148131098, 13911.373988170557, 13923.930380348034, 13937.372818223204, 13965.0, 8479.830403392021, 8771.16673915977, 8793.868091978626, 8883.644654213758, 8894.199522043047, 6755.898661520081, 7251.56490610565, 8846.557672116369, 8857.867131993025, 9055.909440905507, 6587.143671322698, 6727.389733181506, 6730.259671436138, 6734.966325168441, 6857.622830744357, 1600.6718622682322, 1816.3551939061706, 1827.241694696686, 1855.2486243071578, 1858.0709645177412, 937.8171256605033, 950.2398081534744, 950.3110245072268, 1042.5308611124872, 1096.1669131460148, 22494.66258006131, 22645.18643627386, 22661.433464163198, 23053.0, 23126.65310020351, 972.8200282947536, 975.9463229522303, 977.3940157102642, 1025.47700672656, 1033.7777377863795, 11263.324200547993, 11428.771424571461, 11863.34751588671, 11909.987651049758, 12060.819087713691, 516877.0, 525900.0, 540251.0, 560945.0, 563164.0, 64493.67753161204, 94635.0, 120599.39700301443, 121551.0, 123025.0, 959.3428501476511, 991.5587563534302, 1015.2385710716962, 1016.1870503597092, 1050.1021626509378, 19753.604927901648, 19975.20099196016, 20095.397138085882, 20171.697424538936, 20184.990750462126, 26699.065532706263, 37256.81371593125, 52671.73664131655, 53000.73499632477, 53850.461495384545, 72279.63860180666, 72385.0, 72398.0, 74000.62999684968, 75415.62292188503, 7974.561399123104, 8078.394120440873, 8168.428210025241, 8222.259996600334, 8233.752987410388, 13481.460741570227, 13599.456021759019, 13737.048946052368, 13873.930630346784, 13876.167429954236, 11420.657380278604, 11471.246900743594, 11776.823347649963, 11916.702082448008, 12017.579384721494, 10585.576576937145, 11180.552777888794, 11327.376994265394, 11372.772544548898, 11740.882591173979, 9900.504974751379, 11805.173637845268, 11814.940925295663, 11935.0, 12145.210561313486, 8022.5960456920975, 8060.1133875274845, 8418.200270974248, 8445.66217351299, 8485.745427637181, 11314.660560183207, 11339.206255562032, 11654.30074195551, 11656.825147622962, 11711.531538738354], "value": 9886.402333714232, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.9101108427944, 1540.3376548084327, 1574.4568123997221, 1779.4216879514154, 1931.652302585534, 8084.2724154826055, 8875.822483550324, 9126.634934602607, 9149.634014639407, 9281.535923203846, 8987.236084932789, 9081.409708368968, 9087.136722011415, 9094.272458203328, 9103.408278461895, 2298.528801595677, 2328.8951997160084, 2330.8951097200666, 2334.3347146063375, 2335.544568809083, 2349.765023497645, 2382.1305223593436, 2400.2919138853995, 2400.7359190489033, 2403.627437747143, 2366.763323667628, 2380.9047638094457, 2389.761023897605, 2391.868447235401, 2399.2682231919257, 2341.6487526870937, 2353.8823058847074, 2359.4927090675587, 2371.4901296221224, 2382.29722231942, 2323.802476789475, 2350.776676215774, 2356.36378177891, 2356.599378105726, 2362.326736879998, 8996.370254082216, 9143.1771140597, 9154.633814647406, 9161.9921808601, 9167.12912273333, 5271.762770675329, 5478.808241711521, 5495.2856128703415, 5518.117101263779, 5526.613137080406, 3129.890453834105, 3604.5854726706502, 3621.746477746559, 3688.428293614494, 3696.5194524711883, 1051.6056478820442, 1056.788642271541, 1057.931234469757, 1073.0664322039797, 1078.9298695584841, 12030.977366923822, 12032.759344813056, 12111.212771169845, 12157.270563766202, 12180.208286461264, 4446.399736035659, 4483.708558943658, 4676.812927482931, 4677.368555245066, 4678.2982552616695, 9467.337286390024, 9468.857967130498, 9475.0, 9479.47862867549, 9479.905200947902, 5311.920321195185, 5340.25236466895, 5348.224507446476, 5348.358197016383, 5355.758990845421, 4334.588214119716, 4350.369196466495, 4382.780860956939, 4527.479339875957, 4996.4254110777, 3814.6185381462187, 3840.7887566183567, 3852.3836186210074, 3892.1826416452295, 3917.177392747498, 992.5781542844406, 1144.5536240865902, 1160.468181999769, 1163.598558497317, 1173.049829637979, 834.5368320582014, 881.3345923827598, 883.9745894762174, 930.4231376546585, 932.00275704995, 5962.433568810956, 6125.234345706786, 6187.16473276111, 6263.841189379979, 6301.558890877594, 421.13666982685373, 430.4017415792052, 454.56816024776595, 456.1902622844478, 457.76196377883315, 3752.1932784451474, 4041.1109555897656, 4088.3254263046265, 4176.686748493875, 4182.64447521964, 10747.247692661438, 11310.151738619472, 11325.263857849213, 11331.886681133083, 11336.489857956412, 11243.325400475835, 11259.493322800494, 11274.323540587591, 11291.983721465107, 11333.94333028346, 7957.761267161879, 8309.750707478785, 8451.323894088458, 8577.528235946958, 8587.270082043096, 5309.867253318544, 6097.695115244309, 6132.754689812536, 6272.749090036347, 6291.874162516816, 10018.39889606626, 10420.478976051016, 10516.68449946503, 10531.104856087319, 10544.05103540685, 9625.855612165964, 10721.463926803784, 10721.839172412265, 10733.302335348177, 10752.0, 7261.782146535613, 7481.139668938142, 7489.812754681177, 7489.850202995801, 7520.285572870568, 7315.341619254293, 8077.878831817645, 8647.95676021641, 8716.825663486821, 8738.956305218433, 7655.808604784925, 7721.961390193013, 7730.574818384818, 7744.883826742491, 7827.530348179127, 7559.508631938907, 7604.315611594983, 7607.809804754926, 7617.42869284817, 7657.157712651715, 7102.715891364288, 7276.090488688914, 7276.308750668678, 7277.126744790659, 7281.381082608038, 5113.565346945551, 5146.356705411824, 5194.7922083116255, 5230.6846851348555, 5277.498637629419, 5201.375834899836, 5208.453112423293, 5208.760233099803, 5214.191800270964, 5412.079946409199], "value": 4554.230140953261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.49666808096435, 300.78493876878025, 314.6208818373859, 319.872051179528, 322.71278562079755, 4408.779561021948, 4704.600108990731, 5054.115529782287, 5485.561155107592, 5504.339479262494, 4261.872143835682, 5056.671316364434, 5204.970061675949, 5304.904562697732, 5354.8219391733855, 4144.896377590561, 4205.726627769185, 4212.262854000553, 4217.240896638604, 4233.597808208216, 4206.516250631171, 4216.009237829107, 4216.156768646268, 4222.514410842762, 4229.767362795045, 3256.7557433192487, 3586.7668601540818, 3988.7008474364393, 4094.6928980326447, 4165.187788381267, 4042.312806822847, 4087.2847251730827, 4089.4683691120263, 4091.672666186728, 4107.815148318333, 3508.947315805274, 3512.6838584527513, 3521.3485505181625, 3537.3809583323073, 3538.2569660371355, 5014.398272207322, 5266.578673706094, 5280.841574752763, 5309.424928909476, 5316.4683531646915, 4346.630536404409, 4450.732956022616, 4668.762777863869, 4688.132695451352, 4703.106409782131, 5249.370075590953, 5589.776408943678, 5741.555066959832, 5755.280589926259, 5762.942370576325, 3747.887563373103, 3781.149241420658, 3917.196974620196, 3946.9013274668077, 3949.447077409165, 5050.39395272566, 5223.955208958224, 5527.2538207342295, 6030.10051833671, 6123.571350005502, 3540.433530635086, 3764.435334699817, 3960.7029472789654, 3973.2450834341385, 3996.421413541693, 4020.4320315077143, 4049.493813273341, 4061.4517040199785, 4061.9641991292033, 4066.1054567994993, 4584.4498660161, 4672.415948006499, 4695.060987802453, 4732.574068333797, 4743.430788305356, 5725.2843394575675, 5772.538196944234, 5775.797847075332, 5778.0, 5797.492651910449, 1237.8452693413324, 1289.9551363395678, 1308.188922867809, 1364.7270545890863, 1385.0650810702734, 696.5611664651237, 761.4593638516604, 859.4070091636751, 869.1091631078195, 882.6142955559837, 4607.594683621528, 4859.962523422861, 5104.41299250591, 5333.57331413486, 5381.67709937405, 4654.0, 5083.8983220335385, 5215.843524694265, 5624.409437009137, 5706.657600543981, 4423.557644235615, 5045.697258164522, 5341.252224688546, 5369.704666243394, 5505.916686655486, 3108.2540190354152, 3124.7968882022597, 3129.3741251749743, 3134.2634480896972, 3150.779445438797, 6118.20463339763, 6138.938610613926, 6140.956037473551, 6143.662098584531, 6146.969265153645, 4590.42619672541, 6113.755449781959, 6122.387761223931, 6139.109829074849, 6156.107364432132, 6137.386261373917, 6149.0161574148815, 6153.077038444269, 6170.069217121921, 6277.246730392382, 5629.6903670298525, 5752.5705206991215, 5763.904858076952, 5960.374160713236, 5963.403659634176, 5823.70881455934, 5844.853878653068, 5846.590738648254, 5863.885861686266, 5892.970535147297, 5073.644844860824, 5114.820981265638, 6110.388961103854, 6184.113845277234, 6225.3774622537385, 3530.91172720684, 3953.0, 3968.960310396859, 3996.980015099906, 4014.6587540059422, 5615.410381909841, 5947.1079338099635, 5975.40245975399, 6032.306284777307, 6039.305479869871, 5305.65513241638, 5785.884282314414, 5790.218320526675, 5796.76812927495, 5823.621464604787, 4871.585915197106, 5479.0959491683425, 5677.347105082803, 5769.221160140115, 5774.0, 4902.60779137661, 5569.526590239713, 5589.916151257653, 5629.943700562942, 5654.519365853784, 4786.545278198566, 5330.413654498079, 5727.541796656341, 5745.0, 5752.0, 5231.346081739783, 5423.566114710892, 5532.28080349552, 5619.213310136666, 5660.839283126981], "value": 4176.206855002448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.6681443128945, 763.7403282883822, 780.3679019993804, 804.5735760047173, 823.9133895876453, 15002.699946001087, 15916.0, 16401.589960250985, 16452.259648315823, 16455.506334809976, 14855.405783768636, 14938.477153299636, 15127.168005759706, 15133.167675777828, 15333.31000104998, 686.7562015484506, 854.9829003419928, 877.4559772940786, 878.2096113497839, 878.8813510176109, 706.3642721550619, 853.5732133933034, 887.1261807119981, 894.208625366552, 894.3337213775714, 933.2067742418924, 937.8218138553653, 938.6245501799301, 938.7887725261795, 939.1922946266205, 729.2561587181099, 934.425328423015, 935.9719208423792, 936.1527817325332, 936.2837429366506, 662.4005275225928, 684.9075374824434, 687.6492988575812, 688.7933619914056, 690.977353516793, 15361.539153825403, 15383.230838458145, 15396.307166177548, 15404.922975385163, 15412.998155119882, 13981.440742370292, 13985.650358741012, 14115.647108822259, 14276.14343139415, 14468.565943021726, 12985.545505907343, 13099.934500327532, 13113.147645403114, 13323.800142997863, 13362.398692058881, 3829.923401531954, 4607.493175750665, 4623.74843943515, 4687.273472611751, 4717.622590192776, 107116.99999999921, 110403.44798275958, 110798.0, 110808.0, 110850.44574777155, 10967.396793176453, 11144.387058711849, 11161.888381116247, 11245.94377028126, 11772.234804737665, 22498.775012249997, 22505.212317568807, 22519.211827586285, 22522.436939076328, 22573.322800315687, 7848.13670496245, 8009.759707208793, 8063.5968201589685, 8150.51096934174, 8188.7952801180445, 4289.0027474567705, 4387.758673272937, 4469.7318160910445, 4494.07871386365, 4594.609458196024, 3517.2262102337445, 3620.5293311869286, 3636.509071275344, 3641.253543023674, 3648.3980143276594, 594.8364199845078, 625.2590680044162, 643.6459947029209, 654.8101050695341, 673.0308355967392, 570.4267211452546, 631.6336524815688, 634.8793729191439, 645.4191227894933, 650.8340373204898, 8584.527850968132, 9061.275097992146, 9547.379420337795, 10126.139278161292, 10431.68704938853, 521.1765410651162, 585.1895125251557, 638.9361712748222, 648.1832890557837, 686.4782765098461, 4480.484744254387, 4790.329353890458, 4862.41651001882, 4866.586340161055, 4886.633502487328, 87066.56466717625, 121788.0, 133617.33191333982, 145856.5414345843, 158674.0, 79461.20538794654, 112287.0, 116343.0, 117725.41137294259, 125245.37377313054, 107627.0, 108148.4592577032, 108622.45688771663, 109305.45347273213, 114176.4291178572, 8473.66105355779, 9189.494577798017, 9299.256059515357, 9306.92970308396, 9411.0, 53237.7338113307, 54427.45572544224, 56020.159697603754, 56263.71868140633, 58906.41093589009, 88947.0, 90802.54598727229, 91047.54476227841, 92083.0, 94714.52642736743, 13147.0, 13388.397522111723, 13439.0, 13832.308384580529, 14122.929385353007, 14308.713825723635, 14309.64225894361, 14612.123272603678, 14652.402676247075, 14733.92633036828, 13530.200009925895, 14026.649333766738, 14031.508897188549, 14404.495842645458, 14741.262936853329, 12049.759004820029, 13714.17714937107, 14278.857211427752, 14735.115893046452, 14814.703705925605, 11575.42122893835, 13656.251557283022, 13862.722745545234, 14294.785578216542, 14616.561503154922, 11453.541858325907, 11468.598599048993, 11499.435175095747, 11731.648050558155, 11903.40482975865, 8590.226879580869, 9134.148516617515, 11078.46164573235, 11360.0, 11655.358955257543], "value": 7878.104832990019, "unit": "score"}], "name": "dromaeo_css", "value": 7459.7676617044835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 113.0, 113.0, 111.0, 114.0, 112.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 110.0, 194.0, 88.0, 126.0, 163.0, 137.0, 142.0, 106.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 180.0, 184.0, 183.0, 182.0, 184.0, 182.0, 188.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 57.0, 107.0, 57.0, 57.0, 61.0, 61.0, 85.0, 57.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 67.0, 92.0, 71.0, 74.0, 68.0, 95.0, 70.0, 67.0, 100.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 89.0, 86.0, 90.0, 86.0, 88.0, 100.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 149.0, 150.0, 95.0, 96.0, 95.0, 110.0, 149.0, 119.0, 94.0], "value": 116.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 85.0, 84.0, 86.0, 84.0, 84.0, 85.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 54.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 77.0, 83.0, 78.0, 74.0, 73.0, 72.0, 73.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 85.0, 85.0, 87.0, 82.0, 81.0, 84.0, 119.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 151.0, 148.0, 139.0, 136.0, 135.0, 137.0, 156.0, 158.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 221.0, 223.0, 165.0, 166.0, 165.0, 165.0, 165.0, 165.0, 164.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1463.6, "alertThreshold": 2.0}]} 03:27:16 INFO - Return code: 0 03:27:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:27:16 INFO - # TBPL SUCCESS # 03:27:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:27:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:27:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:27:16 INFO - 2016-07-30 03:27:16.987 screenresolution[2258:29096] starting screenresolution argv=screenresolution get 03:27:16 INFO - 2016-07-30 03:27:16.990 screenresolution[2258:29096] Display 0: 1600x1200x32@60 03:27:16 INFO - 2016-07-30 03:27:16.996 screenresolution[2259:29099] starting screenresolution argv=screenresolution list 03:27:16 INFO - Available Modes on Display 0 03:27:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:27:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:27:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:27:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:27:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:27:17 INFO - Intel Iris: 03:27:17 INFO - Chipset Model: Intel Iris 03:27:17 INFO - Type: GPU 03:27:17 INFO - Bus: Built-In 03:27:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:27:17 INFO - Vendor: Intel (0x8086) 03:27:17 INFO - Device ID: 0x0a2e 03:27:17 INFO - Revision ID: 0x0009 03:27:17 INFO - Displays: 03:27:17 INFO - Display: 03:27:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:27:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:27:17 INFO - Display Serial Number: 1600x1200 60 03:27:17 INFO - Main Display: Yes 03:27:17 INFO - Mirror: Off 03:27:17 INFO - Online: Yes 03:27:17 INFO - Rotation: Supported 03:27:17 INFO - Return code: 0 03:27:17 INFO - Running post-action listener: _resource_record_post_action 03:27:17 INFO - [mozharness: 2016-07-30 10:27:17.230868Z] Finished run-tests step (success) 03:27:17 INFO - Running post-run listener: _resource_record_post_run 03:27:17 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 397312; Write bytes: 708227072; Read time: 9; Write time: 1915 03:27:17 INFO - TinderboxPrint: CPU usage
53.4% 03:27:17 INFO - TinderboxPrint: I/O read bytes / time
397,312 / 9 03:27:17 INFO - TinderboxPrint: I/O write bytes / time
708,227,072 / 1,915 03:27:17 INFO - TinderboxPrint: CPU idle
2,260.0 (46.6%) 03:27:17 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 03:27:17 INFO - TinderboxPrint: CPU user
1,621.5 (33.4%) 03:27:17 INFO - TinderboxPrint: Swap in / out
404,553,728 / 0 03:27:17 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396319232; Write bytes: 393070592; Read time: 29713; Write time: 746 03:27:17 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 397312; Write bytes: 297486336; Read time: 9; Write time: 1128 03:27:17 INFO - Running post-run listener: _upload_blobber_files 03:27:17 INFO - Blob upload gear active. 03:27:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:27:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:27:17 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:27:17 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:27:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:27:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:27:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:27:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:19 INFO - (blobuploader) - INFO - Done attempting. 03:27:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:27:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:27:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:20 INFO - (blobuploader) - INFO - Done attempting. 03:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:27:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:27:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:22 INFO - (blobuploader) - INFO - Done attempting. 03:27:22 INFO - (blobuploader) - INFO - Iteration through files over. 03:27:22 INFO - Return code: 0 03:27:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:27:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:27:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d9bf37bb7656b88f3005ac5aeaecd4298841f343a02dbc9b0645e3515de4cf0c64406395e2da68fc13f51c9ad9ea4a06af730b4f233a20d60e2e56a306fc989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/395087404d6bd8f869bed040d5088f470a9aaad422e8042928086c297e320871e9cc8ff014dc03aff20722bc582e98afc04b7edc2aa5c93fdfd77386e941ea14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/667edf98c1f5ded7355a01dc5b937eb038db72006da87dc190a9fa3a02bd55dc779f2af3d0aac308a7fbb7a7c76dd1008c364371f02093eadc173679a4249235"} 03:27:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:27:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:27:22 INFO - Contents: 03:27:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d9bf37bb7656b88f3005ac5aeaecd4298841f343a02dbc9b0645e3515de4cf0c64406395e2da68fc13f51c9ad9ea4a06af730b4f233a20d60e2e56a306fc989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/395087404d6bd8f869bed040d5088f470a9aaad422e8042928086c297e320871e9cc8ff014dc03aff20722bc582e98afc04b7edc2aa5c93fdfd77386e941ea14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/667edf98c1f5ded7355a01dc5b937eb038db72006da87dc190a9fa3a02bd55dc779f2af3d0aac308a7fbb7a7c76dd1008c364371f02093eadc173679a4249235"} 03:27:22 INFO - Running post-run listener: copy_logs_to_upload_dir 03:27:22 INFO - Copying logs to upload dir... 03:27:22 INFO - mkdir: /builds/slave/test/build/upload/logs 03:27:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.596649 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-30 03:27:22.315408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:22.324353) ========= 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.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/Fx-Team/sha512/4d9bf37bb7656b88f3005ac5aeaecd4298841f343a02dbc9b0645e3515de4cf0c64406395e2da68fc13f51c9ad9ea4a06af730b4f233a20d60e2e56a306fc989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/395087404d6bd8f869bed040d5088f470a9aaad422e8042928086c297e320871e9cc8ff014dc03aff20722bc582e98afc04b7edc2aa5c93fdfd77386e941ea14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/667edf98c1f5ded7355a01dc5b937eb038db72006da87dc190a9fa3a02bd55dc779f2af3d0aac308a7fbb7a7c76dd1008c364371f02093eadc173679a4249235"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010957 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4d9bf37bb7656b88f3005ac5aeaecd4298841f343a02dbc9b0645e3515de4cf0c64406395e2da68fc13f51c9ad9ea4a06af730b4f233a20d60e2e56a306fc989", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/395087404d6bd8f869bed040d5088f470a9aaad422e8042928086c297e320871e9cc8ff014dc03aff20722bc582e98afc04b7edc2aa5c93fdfd77386e941ea14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/667edf98c1f5ded7355a01dc5b937eb038db72006da87dc190a9fa3a02bd55dc779f2af3d0aac308a7fbb7a7c76dd1008c364371f02093eadc173679a4249235"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:22.357582) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:22.357955) ========= 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.39CKg4C0jn/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.ktkII0Fy1m/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005318 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:22.522524) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:27:22.522872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:27:22.523569) ========= ========= Total master_lag: 0.32 =========