builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0272 starttime: 1469873176.54 results: success (0) buildid: 20160730030555 builduid: c70282cf131d425ab9d4b5b60857bae9 revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.537273) ========= 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.537693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.538022) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.644279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.644580) ========= 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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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% 35.0M=0s 2016-07-30 03:06:17 (35.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.486184 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.160791) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.161166) ========= 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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.020547 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.209750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:06:17.210140) ========= 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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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,288 truncating revision to first 12 chars 2016-07-30 03:06:17,289 Setting DEBUG logging. 2016-07-30 03:06:17,289 attempt 1/10 2016-07-30 03:06:17,289 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:18,187 unpacking tar archive at: fx-team-c3565c8b1cdb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.167834 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:06:18.407346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.407659) ========= script_repo_revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.408095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.408403) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.427406) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-30 03:06:18.428058) ========= /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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:18 INFO - MultiFileLogger online at 20160730 03:06:18 in /builds/slave/test 03:06:18 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:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:06:18 INFO - {'append_to_log': False, 03:06:18 INFO - 'base_work_dir': '/builds/slave/test', 03:06:18 INFO - 'blob_upload_branch': 'Fx-Team', 03:06:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:06:18 INFO - 'branch': 'Fx-Team', 03:06:18 INFO - 'buildbot_json_path': 'buildprops.json', 03:06:18 INFO - 'config_files': ('talos/mac_config.py',), 03:06:18 INFO - 'default_actions': ('clobber', 03:06:18 INFO - 'read-buildbot-config', 03:06:18 INFO - 'download-and-extract', 03:06:18 INFO - 'populate-webroot', 03:06:18 INFO - 'create-virtualenv', 03:06:18 INFO - 'install', 03:06:18 INFO - 'run-tests'), 03:06:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:06:18 INFO - 'download_minidump_stackwalk': True, 03:06:18 INFO - 'download_symbols': 'ondemand', 03:06:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:06:18 INFO - 'tooltool.py': '/tools/tooltool.py', 03:06:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:06:18 INFO - '/tools/misc-python/virtualenv.py')}, 03:06:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:06:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:06:18 INFO - 'installer_path': 'installer.exe', 03:06:18 INFO - 'log_level': 'info', 03:06:18 INFO - 'log_name': 'talos', 03:06:18 INFO - 'log_to_console': True, 03:06:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:06:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:06:18 INFO - 'opt_config_files': (), 03:06:18 INFO - 'pip_index': False, 03:06:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:06:18 INFO - 'cmd': ('bash', 03:06:18 INFO - '-c', 03:06:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:06:18 INFO - 'enabled': True, 03:06:18 INFO - 'halt_on_failure': False, 03:06:18 INFO - 'name': 'check_screen_resolution'},), 03:06:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:06:18 INFO - 'cmd': ('bash', 03:06:18 INFO - '-c', 03:06:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:06:18 INFO - 'enabled': True, 03:06:18 INFO - 'halt_on_failure': False, 03:06:18 INFO - 'name': 'check_screen_resolution'},), 03:06:18 INFO - 'run_cmd_checks_enabled': True, 03:06:18 INFO - 'sps_profile': False, 03:06:18 INFO - 'sps_profile_interval': 0, 03:06:18 INFO - 'suite': 'dromaeojs', 03:06:18 INFO - 'system_bits': '32', 03:06:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:06:18 INFO - 'title': 't-yosemite-r7-0272', 03:06:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:06:18 INFO - 'use_talos_json': True, 03:06:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:06:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:06:18 INFO - 'work_dir': 'build'} 03:06:18 INFO - [mozharness: 2016-07-30 10:06:18.627968Z] Running clobber step. 03:06:18 INFO - Running pre-action listener: _resource_record_pre_action 03:06:18 INFO - Running main action method: clobber 03:06:18 INFO - rmtree: /builds/slave/test/build 03:06:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:06:20 INFO - Running post-action listener: _resource_record_post_action 03:06:20 INFO - [mozharness: 2016-07-30 10:06:20.199533Z] Finished clobber step (success) 03:06:20 INFO - [mozharness: 2016-07-30 10:06:20.199697Z] Running read-buildbot-config step. 03:06:20 INFO - Running pre-action listener: _resource_record_pre_action 03:06:20 INFO - Running main action method: read_buildbot_config 03:06:20 INFO - Using buildbot properties: 03:06:20 INFO - { 03:06:20 INFO - "project": "", 03:06:20 INFO - "product": "firefox", 03:06:20 INFO - "script_repo_revision": "production", 03:06:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:06:20 INFO - "repository": "", 03:06:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:06:20 INFO - "buildid": "20160730030555", 03:06:20 INFO - "stage_platform": "macosx64", 03:06:20 INFO - "basedir": "/builds/slave/test", 03:06:20 INFO - "buildnumber": 113, 03:06:20 INFO - "platform": "yosemite_r7", 03:06:20 INFO - "mozci_request": { 03:06:20 INFO - "priority": -1, 03:06:20 INFO - "type": "trigger_all_talos_jobs", 03:06:20 INFO - "times": 6 03:06:20 INFO - }, 03:06:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:06:20 INFO - "slavebuilddir": "test", 03:06:20 INFO - "branch": "fx-team", 03:06:20 INFO - "revision": "c3565c8b1cdb575db1c80c7791984a6490598b84", 03:06:20 INFO - "slavename": "t-yosemite-r7-0272", 03:06:20 INFO - "builduid": "c70282cf131d425ab9d4b5b60857bae9", 03:06:20 INFO - "repo_path": "integration/fx-team" 03:06:20 INFO - } 03:06:20 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:20 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:20 INFO - Running post-action listener: _resource_record_post_action 03:06:20 INFO - [mozharness: 2016-07-30 10:06:20.206501Z] Finished read-buildbot-config step (success) 03:06:20 INFO - [mozharness: 2016-07-30 10:06:20.206734Z] Running download-and-extract step. 03:06:20 INFO - Running pre-action listener: _resource_record_pre_action 03:06:20 INFO - Running main action method: download_and_extract 03:06:20 INFO - mkdir: /builds/slave/test/build/tests 03:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:20 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:20 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:20 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:20 INFO - Downloaded 1199 bytes. 03:06:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:06:20 INFO - Using the following test package requirements: 03:06:20 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:06:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:06:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'jsshell-mac.zip'], 03:06:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:06:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:06:20 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:06:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:06:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:20 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:06:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:20 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:20 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:20 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:20 INFO - Downloaded 16300348 bytes. 03:06:20 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:20 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:23 INFO - Return code: 0 03:06:23 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:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:23 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:23 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:23 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:27 INFO - Return code: 0 03:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:27 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:27 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:27 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:38 INFO - Downloaded 111598219 bytes. 03:06:38 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:38 INFO - mkdir: /builds/slave/test/properties 03:06:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:06:38 INFO - Writing to file /builds/slave/test/properties/build_url 03:06:38 INFO - Contents: 03:06:38 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg 03:06:38 INFO - Running post-action listener: _resource_record_post_action 03:06:38 INFO - Running post-action listener: set_extra_try_arguments 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.809640Z] Finished download-and-extract step (success) 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.809806Z] Running populate-webroot step. 03:06:38 INFO - Running pre-action listener: _resource_record_pre_action 03:06:38 INFO - Running main action method: populate_webroot 03:06:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:06:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:06:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:06:38 INFO - '--tppagecycles', 03:06:38 INFO - '7'], 03:06:38 INFO - 'tests': ['tsvgm']}}, 03:06:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:06:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:06:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:06:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:06:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:06:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:06:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['damp', 'tps']}, 03:06:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'tests': ['damp', 'tps']}, 03:06:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['dromaeo_dom']}, 03:06:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:06:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['basic_compositor_video']}, 03:06:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:06:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['a11yr', 03:06:38 INFO - 'ts_paint', 03:06:38 INFO - 'tpaint', 03:06:38 INFO - 'sessionrestore', 03:06:38 INFO - 'sessionrestore_no_auto_restore', 03:06:38 INFO - 'tabpaint']}, 03:06:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['tsvgx', 03:06:38 INFO - 'tsvgr_opacity', 03:06:38 INFO - 'tart', 03:06:38 INFO - 'tscrollx', 03:06:38 INFO - 'cart']}, 03:06:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:06:38 INFO - 'tsvgr_opacity', 03:06:38 INFO - 'tart', 03:06:38 INFO - 'tscrollx', 03:06:38 INFO - 'cart']}, 03:06:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'talos_options': ['--disable-e10s'], 03:06:38 INFO - 'tests': ['tp5o']}, 03:06:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'tests': ['tp5o']}, 03:06:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'talos_options': ['--disable-e10s', 03:06:38 INFO - '--xperf_path', 03:06:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:06:38 INFO - 'tests': ['tp5n']}, 03:06:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:06:38 INFO - 'talos_options': ['--xperf_path', 03:06:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:06:38 INFO - 'tests': ['tp5n']}}, 03:06:38 INFO - 'talos.zip': {'path': '', 03:06:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:06:38 INFO - Running post-action listener: _resource_record_post_action 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.829154Z] Finished populate-webroot step (success) 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.829261Z] Running create-virtualenv step. 03:06:38 INFO - Running pre-action listener: _resource_record_pre_action 03:06:38 INFO - Running main action method: create_virtualenv 03:06:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:06:38 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:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:06:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:06:38 INFO - Using real prefix '/tools/python27' 03:06:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:06:39 INFO - Installing distribute.............................................................................................................................................................................................done. 03:06:42 INFO - Installing pip.................done. 03:06:42 INFO - Return code: 0 03:06:42 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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', 'pip>=1.5'] 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 pip>=1.5 03:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/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.54WSal5sVH/Listeners', 03:06:42 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 - 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:42 INFO - Cleaning up... 03:06:42 INFO - Return code: 0 03:06:42 INFO - Installing psutil>=3.1.1 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', '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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 03:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/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.54WSal5sVH/Listeners', 03:06:42 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:43 INFO - Downloading/unpacking psutil>=3.1.1 03:06:43 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:43 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:43 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:43 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:43 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:43 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:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:06:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:06:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:06:46 INFO - Installing collected packages: psutil 03:06:46 INFO - Running setup.py install for psutil 03:06:46 INFO - building 'psutil._psutil_osx' extension 03:06:46 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:47 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:47 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:47 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:47 INFO - building 'psutil._psutil_posix' extension 03:06:47 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:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:06:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:06:47 INFO - ^ 03:06:47 INFO - 1 warning generated. 03:06:47 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:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:06:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:06:47 INFO - Successfully installed psutil 03:06:47 INFO - Cleaning up... 03:06:47 INFO - Return code: 0 03:06:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:47 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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:47 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:47 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:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:47 INFO - 'HOME': '/Users/cltbld', 03:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:47 INFO - 'LOGNAME': 'cltbld', 03:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:47 INFO - 'MOZ_NO_REMOTE': '1', 03:06:47 INFO - 'NO_EM_RESTART': '1', 03:06:47 INFO - 'PAGER': '/bin/cat', 03:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:47 INFO - 'PWD': '/builds/slave/test', 03:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:47 INFO - 'SHELL': '/bin/bash', 03:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:06:47 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:47 INFO - 'USER': 'cltbld', 03:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:47 INFO - 'XPC_FLAGS': '0x0', 03:06:47 INFO - 'XPC_SERVICE_NAME': '0', 03:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:06:47 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:47 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:47 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:47 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:47 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:47 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:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:06:50 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:50 INFO - Installing collected packages: mozsystemmonitor 03:06:50 INFO - Running setup.py install for mozsystemmonitor 03:06:50 INFO - Successfully installed mozsystemmonitor 03:06:50 INFO - Cleaning up... 03:06:50 INFO - Return code: 0 03:06:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:50 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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:50 INFO - 'HOME': '/Users/cltbld', 03:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:50 INFO - 'LOGNAME': 'cltbld', 03:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:50 INFO - 'MOZ_NO_REMOTE': '1', 03:06:50 INFO - 'NO_EM_RESTART': '1', 03:06:50 INFO - 'PAGER': '/bin/cat', 03:06:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:50 INFO - 'PWD': '/builds/slave/test', 03:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:50 INFO - 'SHELL': '/bin/bash', 03:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:06:50 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:50 INFO - 'USER': 'cltbld', 03:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:50 INFO - 'XPC_FLAGS': '0x0', 03:06:50 INFO - 'XPC_SERVICE_NAME': '0', 03:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:50 INFO - Downloading/unpacking jsonschema==2.5.1 03:06:50 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:50 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:50 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:50 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:50 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:50 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 jsonschema-2.5.1-py2.py3-none-any.whl 03:06:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:06:53 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:53 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 - 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:53 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:53 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:53 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 functools32-3.2.3-2.tar.gz 03:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:06:53 INFO - warning: no files found matching '*.txt' 03:06:53 INFO - no previously-included directories found matching 'build' 03:06:53 INFO - no previously-included directories found matching 'dist' 03:06:53 INFO - no previously-included directories found matching '.git*' 03:06:53 INFO - Installing collected packages: jsonschema, functools32 03:06:53 INFO - Running setup.py install for functools32 03:06:53 INFO - warning: no files found matching '*.txt' 03:06:53 INFO - no previously-included directories found matching 'build' 03:06:53 INFO - no previously-included directories found matching 'dist' 03:06:53 INFO - no previously-included directories found matching '.git*' 03:06:54 INFO - Successfully installed jsonschema functools32 03:06:54 INFO - Cleaning up... 03:06:54 INFO - Return code: 0 03:06:54 INFO - Installing functools32==3.2.3-2 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', '--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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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', '--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:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/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.54WSal5sVH/Listeners', 03:06:54 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:06:54 INFO - Cleaning up... 03:06:54 INFO - Return code: 0 03:06:54 INFO - Installing blobuploader==1.2.4 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', '--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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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', '--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:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/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.54WSal5sVH/Listeners', 03:06:54 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 - Downloading/unpacking blobuploader==1.2.4 03:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:06:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:06:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:58 INFO - Downloading docopt-0.6.1.tar.gz 03:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:06:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:06:58 INFO - Running setup.py install for blobuploader 03:06:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:06:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:06:58 INFO - Running setup.py install for requests 03:06:58 INFO - Running setup.py install for docopt 03:06:59 INFO - Successfully installed blobuploader requests docopt 03:06:59 INFO - Cleaning up... 03:06:59 INFO - Return code: 0 03:06:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:59 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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:59 INFO - 'HOME': '/Users/cltbld', 03:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:59 INFO - 'LOGNAME': 'cltbld', 03:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:59 INFO - 'MOZ_NO_REMOTE': '1', 03:06:59 INFO - 'NO_EM_RESTART': '1', 03:06:59 INFO - 'PAGER': '/bin/cat', 03:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:59 INFO - 'PWD': '/builds/slave/test', 03:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:59 INFO - 'SHELL': '/bin/bash', 03:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:06:59 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:59 INFO - 'USER': 'cltbld', 03:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:59 INFO - 'XPC_FLAGS': '0x0', 03:06:59 INFO - 'XPC_SERVICE_NAME': '0', 03:06:59 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/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-iwrnDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:06:59 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-gSYIIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:06:59 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-plaIuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:06:59 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-7Arke3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:06:59 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-oUJksP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:06:59 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jSnkdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-3D4uu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-66yTgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-kLUtvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-bxEgT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-gNjKRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Z03wu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-hnI5wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-rAv0lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-2MPk0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:07:00 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-EEi4Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-7Nh74Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:07:01 INFO - Running setup.py install for manifestparser 03:07:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Running setup.py install for mozcrash 03:07:01 INFO - Running setup.py install for mozdebug 03:07:01 INFO - Running setup.py install for mozdevice 03:07:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Running setup.py install for mozfile 03:07:01 INFO - Running setup.py install for mozhttpd 03:07:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for mozinfo 03:07:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for mozInstall 03:07:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for mozleak 03:07:02 INFO - Running setup.py install for mozlog 03:07:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for moznetwork 03:07:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for mozprocess 03:07:02 INFO - Running setup.py install for mozprofile 03:07:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:07:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:07:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:07:03 INFO - Running setup.py install for mozrunner 03:07:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:07:03 INFO - Running setup.py install for mozscreenshot 03:07:03 INFO - Running setup.py install for moztest 03:07:03 INFO - Running setup.py install for mozversion 03:07:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:07:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:07:03 INFO - Cleaning up... 03:07:03 INFO - Return code: 0 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/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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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/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:07:03 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:07:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/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.54WSal5sVH/Listeners', 03:07:03 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-nq_WBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-W1byE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-pZCVB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-wYwmTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Ll3svZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-A3dBXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Lkp2LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-2re2al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jDj_AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:07:04 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:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:07:04 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-3KiZj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-fFt5dN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-OJvaWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-skptUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-NFsfoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-GDRbhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-QIqL_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:05 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-b_sO8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:05 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:05 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:05 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:05 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:05 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:05 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:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:07:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:08 INFO - Downloading blessings-1.6.tar.gz 03:07:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:07:08 INFO - Installing collected packages: blessings 03:07:08 INFO - Running setup.py install for blessings 03:07:08 INFO - Successfully installed blessings 03:07:08 INFO - Cleaning up... 03:07:08 INFO - Return code: 0 03:07:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:07:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:07:08 INFO - Reading from file tmpfile_stdout 03:07:08 INFO - Current package versions: 03:07:08 INFO - blessings == 1.6 03:07:08 INFO - blobuploader == 1.2.4 03:07:08 INFO - docopt == 0.6.1 03:07:08 INFO - functools32 == 3.2.3-2 03:07:08 INFO - jsonschema == 2.5.1 03:07:08 INFO - manifestparser == 1.1 03:07:08 INFO - mozInstall == 1.12 03:07:08 INFO - mozcrash == 0.17 03:07:08 INFO - mozdebug == 0.1 03:07:08 INFO - mozdevice == 0.48 03:07:08 INFO - mozfile == 1.2 03:07:08 INFO - mozhttpd == 0.7 03:07:08 INFO - mozinfo == 0.9 03:07:08 INFO - mozleak == 0.1 03:07:08 INFO - mozlog == 3.3 03:07:08 INFO - moznetwork == 0.27 03:07:08 INFO - mozprocess == 0.23 03:07:08 INFO - mozprofile == 0.28 03:07:08 INFO - mozrunner == 6.12 03:07:08 INFO - mozscreenshot == 0.1 03:07:08 INFO - mozsystemmonitor == 0.3 03:07:08 INFO - moztest == 0.7 03:07:08 INFO - mozversion == 1.4 03:07:08 INFO - psutil == 3.1.1 03:07:08 INFO - requests == 1.2.3 03:07:08 INFO - wsgiref == 0.1.2 03:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:07:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:08 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:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:08 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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:08 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:08 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:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:08 INFO - 'HOME': '/Users/cltbld', 03:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:08 INFO - 'LOGNAME': 'cltbld', 03:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:08 INFO - 'MOZ_NO_REMOTE': '1', 03:07:08 INFO - 'NO_EM_RESTART': '1', 03:07:08 INFO - 'PAGER': '/bin/cat', 03:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:08 INFO - 'PWD': '/builds/slave/test', 03:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:08 INFO - 'SHELL': '/bin/bash', 03:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:07:08 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:08 INFO - 'USER': 'cltbld', 03:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:08 INFO - 'XPC_FLAGS': '0x0', 03:07:08 INFO - 'XPC_SERVICE_NAME': '0', 03:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 INFO - Cleaning up... 03:07:09 INFO - Return code: 0 03:07:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:07:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:07:09 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 0x10e927a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4048aaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, '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.x0LSlXoNff/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.54WSal5sVH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:07:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:09 INFO - 'HOME': '/Users/cltbld', 03:07:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:09 INFO - 'LOGNAME': 'cltbld', 03:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:09 INFO - 'MOZ_NO_REMOTE': '1', 03:07:09 INFO - 'NO_EM_RESTART': '1', 03:07:09 INFO - 'PAGER': '/bin/cat', 03:07:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:09 INFO - 'PWD': '/builds/slave/test', 03:07:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:09 INFO - 'SHELL': '/bin/bash', 03:07:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:07:09 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:07:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:09 INFO - 'USER': 'cltbld', 03:07:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:09 INFO - 'XPC_FLAGS': '0x0', 03:07:09 INFO - 'XPC_SERVICE_NAME': '0', 03:07:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:07:09 INFO - Cleaning up... 03:07:09 INFO - Return code: 0 03:07:09 INFO - Running post-action listener: _resource_record_post_action 03:07:09 INFO - Running post-action listener: _start_resource_monitoring 03:07:09 INFO - Starting resource monitoring. 03:07:09 INFO - [mozharness: 2016-07-30 10:07:09.526922Z] Finished create-virtualenv step (success) 03:07:09 INFO - [mozharness: 2016-07-30 10:07:09.527728Z] Running install step. 03:07:09 INFO - Running pre-action listener: _resource_record_pre_action 03:07:09 INFO - Running main action method: install 03:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:07:09 INFO - Reading from file tmpfile_stdout 03:07:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:07:09 INFO - Reading from file tmpfile_stdout 03:07:09 INFO - Output received: 03:07:09 INFO - Usage: mozinstall [options] installer 03:07:09 INFO - Options: 03:07:09 INFO - -h, --help show this help message and exit 03:07:09 INFO - -d DEST, --destination=DEST 03:07:09 INFO - Directory to install application into. [default: 03:07:09 INFO - "/builds/slave/test"] 03:07:09 INFO - --app=APP Application being installed. [default: firefox] 03:07:09 INFO - mkdir: /builds/slave/test/build/application 03:07:09 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:09 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:43 INFO - Reading from file tmpfile_stdout 03:07:43 INFO - Output received: 03:07:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:07:43 INFO - Running post-action listener: _resource_record_post_action 03:07:43 INFO - [mozharness: 2016-07-30 10:07:43.693087Z] Finished install step (success) 03:07:43 INFO - [mozharness: 2016-07-30 10:07:43.693245Z] Running run-tests step. 03:07:43 INFO - Running pre-action listener: _resource_record_pre_action 03:07:43 INFO - Running main action method: run_tests 03:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:43 INFO - 2016-07-30 03:07:43.708 screenresolution[1942:18640] starting screenresolution argv=screenresolution get 03:07:43 INFO - 2016-07-30 03:07:43.710 screenresolution[1942:18640] Display 0: 1600x1200x32@60 03:07:43 INFO - 2016-07-30 03:07:43.717 screenresolution[1943:18642] starting screenresolution argv=screenresolution list 03:07:43 INFO - Available Modes on Display 0 03:07:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:43 INFO - Intel Iris: 03:07:43 INFO - Chipset Model: Intel Iris 03:07:43 INFO - Type: GPU 03:07:43 INFO - Bus: Built-In 03:07:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:43 INFO - Vendor: Intel (0x8086) 03:07:43 INFO - Device ID: 0x0a2e 03:07:43 INFO - Revision ID: 0x0009 03:07:43 INFO - Displays: 03:07:43 INFO - Display: 03:07:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:43 INFO - Display Serial Number: 1600x1200 60 03:07:43 INFO - Main Display: Yes 03:07:43 INFO - Mirror: Off 03:07:43 INFO - Online: Yes 03:07:43 INFO - Rotation: Supported 03:07:43 INFO - Return code: 0 03:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:07:43 INFO - Python 2.7.3 03:07:43 INFO - Return code: 0 03:07:43 INFO - grabbing minidump binary from tooltool 03:07:43 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:43 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 0x10e88bd30>, '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 0x10e5f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e883b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:07:43 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:43 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:43 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:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:07:44 INFO - Return code: 0 03:07:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:07:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:07:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:07:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:07:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:07:44 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-0272', '--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:44 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-0272 --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:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x0LSlXoNff/Render', 03:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:44 INFO - 'HOME': '/Users/cltbld', 03:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:44 INFO - 'LOGNAME': 'cltbld', 03:07:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:07:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:44 INFO - 'MOZ_NO_REMOTE': '1', 03:07:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:07:44 INFO - 'NO_EM_RESTART': '1', 03:07:44 INFO - 'PAGER': '/bin/cat', 03:07:44 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:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:44 INFO - 'PWD': '/builds/slave/test', 03:07:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:44 INFO - 'SHELL': '/bin/bash', 03:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54WSal5sVH/Listeners', 03:07:44 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 03:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:44 INFO - 'USER': 'cltbld', 03:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:44 INFO - 'XPC_FLAGS': '0x0', 03:07:44 INFO - 'XPC_SERVICE_NAME': '0', 03:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:44 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-0272', '--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:44 INFO - mozversion application_buildid: 20160730005025 03:07:44 INFO - mozversion application_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:44 INFO - mozversion application_display_name: Nightly 03:07:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:07:44 INFO - mozversion application_name: Firefox 03:07:44 INFO - mozversion application_remotingname: firefox 03:07:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:07:44 INFO - mozversion application_vendor: Mozilla 03:07:44 INFO - mozversion application_version: 50.0a1 03:07:44 INFO - mozversion platform_buildid: 20160730005025 03:07:44 INFO - mozversion platform_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:07:44 INFO - mozversion platform_version: 50.0a1 03:07:44 INFO - using testdate: 1469873264 03:07:44 INFO - actual date: 1469873264 03:07:44 INFO - starting webserver on 'localhost:52107' 03:07:44 INFO - SUITE-START | Running 2 tests 03:07:44 INFO - TEST-START | dromaeo_css 03:07:44 INFO - operating with platform_type : mac_ 03:07:44 INFO - Initialising browser for dromaeo_css test... 03:07:44 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpuUijhK/profile http://localhost:52107/getInfo.html) 03:07:46 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 03:07:46 INFO - PROCESS | 1953 | colorDepth:24 03:07:46 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 03:07:46 INFO - PROCESS | 1953 | __metrics 03:07:46 INFO - PROCESS | 1953 | 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:46 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:07:47 INFO - TEST-INFO | 1953: exit 0 03:07:47 INFO - Browser initialized. 03:07:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:07:47 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpuUijhK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:59 INFO - PROCESS | 1955 | RSS: Main: 243236864 03:07:59 INFO - PROCESS | 1955 | 03:08:51 INFO - PROCESS | 1955 | 1469873331172 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:11:01 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52107/tests/dromaeo/cssquery-ext.html) 03:11:02 INFO - PROCESS | 1955 | RSS: Main: 351662080 03:11:02 INFO - PROCESS | 1955 | 03:14:05 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-ext.html (next: http://localhost:52107/tests/dromaeo/cssquery-jquery.html) 03:14:06 INFO - PROCESS | 1955 | RSS: Main: 386338816 03:14:06 INFO - PROCESS | 1955 | 03:17:08 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52107/tests/dromaeo/cssquery-mootools.html) 03:17:09 INFO - PROCESS | 1955 | RSS: Main: 380055552 03:17:09 INFO - PROCESS | 1955 | 03:20:11 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52107/tests/dromaeo/cssquery-prototype.html) 03:20:12 INFO - PROCESS | 1955 | RSS: Main: 372920320 03:20:12 INFO - PROCESS | 1955 | 03:23:15 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52107/tests/dromaeo/cssquery-yui.html) 03:23:16 INFO - PROCESS | 1955 | RSS: Main: 344195072 03:23:16 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-yui.html (next: http://localhost:52107/tests/dromaeo/cssquery-dojo.html) 03:26:19 INFO - PROCESS | 1955 | RSS: Main: 581627904 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | __start_tp_report 03:26:19 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 03:26:19 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 03:26:19 INFO - PROCESS | 1955 | |i|pagename|runs| 03:26:19 INFO - PROCESS | 1955 | |0;dojo.html;3327.9334413311735;3337.098983274516;3367.6632336766324;3375.8312084395807;3410.0963244740146;5556.777728890839;5600.439956004403;5676.631018983763;5732.312122545295;5757.107648314507;5641.464060914217;5673.035583950728;5795.1886735857015;5796.652200867951;5851.473367396933;5128.871778205537;5160.948390516103;5185.325907632003;5191.844244672666;5276.15581506959;5311.7344132793205;5316.388615309231;5320.17537281722;5333.786648534054;5361.463853614647;4394.692371533978;4946.7526623668855;5118.769655365518;5172.741362931876;5176.50823171798;5126.794928202868;5134.691918484903;5150.227465880148;5205.089109405839;5216.1393370094265;5085.974570127126;5141.383034035902;5170;5193.246979187997;5251.842444726626;5688.829335119953;5707.14392841073;5755.68343741094;5768.826935191951;5772;6113.327533971304;6143.539234557381;6210.9068363974575;6222.097795819626;6274.874502509924;6214.751409943597;6246.375362463763;6262.843428914268;6293.559450838443;6297;5647.29408823897;5884.941150588482;5890.204822348971;5892.734826932799;5897.056470964625;6164.753409863645;6244.188255526752;6268.968655156695;6274.749010039547;6275.654838983808;6125.632462052291;6136.969315153396;6148.954677704801;6152.384761523901;6154.969225153845;6116.548817443993;6163.38366163389;6262.091996660459;6265.373462653698;6270.717817698214;6115.270764408881;6159.353267906895;6194.318624951342;6197.81406557804;6257.687115644199;6261.373862613793;6261.655608941461;6269.216347956505;6271.310155882849;6288.434040936246;4937.7777999990085;4945.134601444733;4946.777395017233;4952.8514144575;4983.501649834988;4341.978290108529;4357.259265924737;4417.602415782595;4438.889027774267;4479.216137175981;5777.393373695785;5781.335146458211;5783.537317014629;5786.508146807484;5861.062230043172;6138.938610613836;6262.530310226751;6277.089821975788;6280.685965701697;6304.432601065926;5643.802466913638;5681.914771278434;5704.914426283609;5775.595708300379;5775.913361299584;5453.127499600045;5458.45415458459;5470.425605311385;5477.986572484103;5506.449355064461;6316.336784637639;6319.05214217871;6352.36476352361;6354.52341074412;6361.968190159019;6104.519788040365;6171.722272497838;6240.313565507699;6259.3427690091885;6266.310705822447;6293.748250069946;6336.39804218617;6344.333844946214;6349.269833969152;6349.333320001333;6184.381561843959;6213.2233470816145;6219.751209951552;6220.533459990428;6227.532935029801;5561.277033985556;6113.841195312776;6166.81499555014;6207.751689932533;6280.560360774702;6161.599496032743;6168.013117901114;6176.166217560752;6182.28903676083;6204.100405441095;6140.9078863816185;6151.138840562236;6154.353792851686;6155.815325540241;6157.568970172045;5862.912056319074;5963.642181469126;6017.789377371946;6029.1559181715475;6035.939640603538;6135.509159267159;6152.323244443197;6173.043178307369;6179;6185.443310102112;5964.493018093511;5992.509363295901;6003.249593800775;6006.819795406145;6071.908921366095 03:26:19 INFO - PROCESS | 1955 | |1;ext.html;523.1394356283913;530.4483337329181;535.4779090386874;551.6083580657731;552.5220684108248;30745.84627076864;34314.65685343146;40661.186776264454;41878;44547.77726111373;43205.78397108018;43383.132337353316;43444.131117377634;43490.130197396036;43515.782421087926;9641.421514709105;9828.164606008499;9829.115379615832;9838.311318207729;9861.852072218922;8701.08638592948;9046.3667543272;11691.356975366352;11839.230450020765;11865.584704535342;11916.046716262677;12042.397880106004;12064.879351206464;12068.758624827498;12078.396080195998;11935.701607459796;12000;12012.03903687703;12013.759724805499;12015.459304331325;11852.525898964117;11917.22538035025;11933.761324773455;11935;11938.28370297785;41222.38166427506;41465;41477;41532.792336038125;41565.1686966259;3075.3080556874747;3416.521686963827;3459.775114617542;3460.1522626956557;3837.616238376168;32189.51715724266;32962;33443.83278083594;33448.6655133448;33579.496307555404;3239.3521295740948;3327.517509961042;3428.5714285714284;3436.0035589678964;3481.3385456763135;500715;501508;503875;505029;505789;51611.22583161256;62533.68733156305;62942.37057629457;63051;63090.68454657697;66324;66459.33540664532;66490.33509664939;66545.6672716643;66574.66712666502;15776.76334854978;15985.600359991093;15993.200339982954;15999.920000399923;16025.519234422985;11075.335479871233;11261.211715180023;12304.630861074003;12383.009359251238;12390.442430090667;15059.472918447802;15394.692106157814;15397.845161612924;15398.46105386306;17992.550186245186;4910.29272898959;6213.2233470816145;6332.905006424908;6352.428281454691;6354.601987562729;969.6896992962326;1030.1552726763982;1060.1359891688248;1080.3301952789163;1103.3986477369854;30878.69121308803;30903.536446953312;31053.689463105533;31438;31655;1131.7623299107279;1157.288267715366;1161.848959635242;1177.205386364218;1185.0045961392473;15173.544793656209;15767.29047192879;15851.603709907344;15913.68172636564;16014.599635009217;663964;666525;667770;670077;672397;52556.737216313675;59613.40386596078;60210;60366.69816650889;65016;1394.2401391241797;1421.8364888037802;1444.400573761877;1445.9566213013627;1446.978295325571;31212.063638090895;31930;32183;33018.009459716246;33028.66971330256;53021.73489132529;57449.712751435974;58949.70525147347;59208;62446.06330905131;631066;636673;637004;637248;637803;26641.86679066592;27033.45933081288;27040.1887943362;27124.32189195207;27134.457310854068;48495.75752121217;48561.51438485711;48588.75705621449;49991.25013124733;50656.493435065175;39963.80018100007;40203.79898100491;40524.25968448911;41359;41694.79152604339;35277.82361088178;39602;39972.60027399689;40100.799496002335;41427.964300891515;38902.22195556016;39373.21253574855;39758;39780.60219397885;39799.80100099481;26921.057762978202;33585.66414335923;33651.326973460884;35257;36628.08429789277;25851.870740646176;26241.4751704961;26877.328066797705;27180.048698295464;27599.44801104007 03:26:19 INFO - PROCESS | 1955 | |2;jquery.html;340.8534330237998;347.7444078602225;376.9736118471707;378.35868203395245;406.56091421265035;15482.84517154828;16868.409605663805;20992.475188120305;21255.787442125573;21321.786782132174;16453.917730411362;20307.984600769978;21047.789522104773;22391.664125038136;22447.55104897905;4422.867313980586;5327.813526526573;5333.919991200135;5448.591355648342;5451.972740136293;5346.8395948121615;5426.945730542703;5436.510714035736;5441.863953401157;5444.8366549003595;3946.940795888064;5826.300843898738;6053.606515576495;6095.939040609582;6140.692965351737;6004;6044.637321760709;6109.938900611004;6114.205153330038;6115.388461153893;5646.576506761969;5775.682337471438;5783;5786.0742281235225;5786.768529258825;10545.841812372744;13078.673033174247;13229.801552976713;13392.531261405902;13406.731865362639;1847.898365589892;1850.6576283387617;2024.9190032398687;2085.343116918166;2275.7724227577273;10113.393196408239;10160.94919525405;10312.432816195107;11380.544778208945;16380.508584742476;1784.4200634793615;1829.2134382215643;1867.374429566097;1940.7477027986272;1943.446117856404;104266.47866760618;109177.45411272973;111296.4435177827;111572;113789;9464.95267523658;11467.311961282183;11512.827307590393;11666.300021998708;11683.299002059903;14742.705145897022;14767.630809229855;14795.704085918222;14796.18620975835;14806.111633302036;6716.7984960450285;8078.192180781993;8229.835403291901;8403.41176117666;8417.116202798617;7189.2810718927685;8026.277635012877;8044.115147333789;8193.918060819435;8199.508029518129;4497.617702495325;4583.220852455024;4594.287885377772;4674.95325046752;4681.157391669532;1633.3303345628235;1893.8977734714456;1976.7859996803672;1990.2238127130431;1994.9501262468555;853.6073406233203;892;927.8077670193769;936.8501039833731;941.1952780372683;20440.053884149198;21946.012429440714;22010.559788804134;22032.559348813254;22326.995285212204;801.5791709352636;864.9534063782893;910.8588168833842;929.9023602521638;964.9517524123906;11066.39134847575;11307.982281594695;11466.541338346373;11486.253393529394;11549.942250288863;617103;620705.8964705147;629160;631629;633172;86222;132011;138181;138520.30739846238;142292;960.6829746183603;961.4663861556763;989.8515222716507;991.7669347703284;992.3153024413132;14629.92685036568;17654.823451765318;18228.544286392946;19338.226470941005;19573.25068542152;53108.734456327475;54117;54808.177877332666;54895;58147.70926145342;79404.60297698705;82295.17704822875;84546.57726711327;84580.57709711412;85563.5721821387;7305.575412794454;7577.242275772379;8025.799355016171;8209.507429554245;8237.546934918637;12589.748205035665;13138.540151094665;13335.733285334045;13775.242361670204;13777.862221377658;11392.088632909514;11546.480408381474;11598.594049208237;11718.945294923498;11749.88250117488;9047;11358.375289359165;11491.310521368745;11562.88437115618;11773.705657358634;9310.487923164292;10428.217883658604;11441.828372574251;11582.899624535656;11807.704807379885;7800.765977020698;7804.297613214838;7836.490628109154;7850.214978502104;7996;7550.207228240955;9568.904310956801;11348.262362946381;11625.476853541442;11683.824742628698 03:26:19 INFO - PROCESS | 1955 | |3;mootools.html;1374.931253437328;1517.2792923361405;1558.7272227360213;1785.4554360919915;1964.3615824856925;8106.797330066751;8484.448510846798;8487.575621218944;8490.78773030673;8490.98108227012;8663.870041949376;8866.645334186625;8938.329625278098;9067.183953444188;9074.77313067172;2388.1641425501066;2419.334682962182;2421.6125419932814;2423.9394015149587;2426.502566973766;2355.2463211772238;2395.4370722880108;2399.2322456813827;2405.7594240575977;2408.2534414331526;2358.221786810357;2363.6809030780882;2378.143868207444;2393.617021276596;2401.0515846240723;2320.6286994081033;2342.508073304608;2350.247920665388;2359.8938047787806;2371.1226846066893;2318.14228735369;2348.5889969255313;2352.482453860148;2352.5294941011693;2354.6703461515403;9034.728958131267;9102.590383432762;9126.26989840811;9130.17828395441;9152.359334846564;5256.053910296145;5266.815661451869;5419.376771671269;5423.810166644149;5428.5385742211565;3498.702610278129;3545.5745310562897;3548.7870727756417;3555.5555555555557;3562.0382496725997;1031.1750599520428;1042.7549327853867;1044.958201671932;1058.6506452870535;1071.4803320389606;12041.096917731205;12222.511099556095;12229.388530573524;12231.266124032587;12236.632901012896;3921.9607803922167;4537.727736335831;4571.245744452194;4624.190766615896;4704.223803072455;9513.476758778195;9528.523573821281;9535.094166054214;9535.094166054214;9547.856782148274;5301.443348448434;5309.230161626542;5310.575153987672;5314.335708036496;5318.228856815817;4108.876733697994;4427.269500532376;4557.840475583339;4588.7246765194195;4954.578860796801;3792.905177370588;3819;3931.056546428894;3935.0752573145287;3937.192875460581;996.2478328861663;1105.894105894106;1144.8797876223043;1163.6858048326903;1176.0944073063692;641.7625478572958;733.4645708632621;807.858624740668;822.2599660305654;922.6540047482194;5747.3103227612955;5872.559558033165;5874.5887787854435;5918.644881307136;6018.6990650467305;425.3768229543733;434.0949121082578;438.39939283182247;946.2571881073462;1015.441507171053;3775.2826962876966;3840.308744425974;3894.7078969077434;4020;4021.477207962946;10836.295640783324;11094.5562177512;11192.944035279772;11209.21535492524;11257.943710281395;10362.274640775073;10770.838437423445;10972;10985.560577576967;11177.552897884156;8136.593170341459;8330.50016998982;8540.786480337916;8544.914550854537;8553.615087321086;5101.642884998015;5966.403359663999;5994.400559943971;6101.237345331833;6147.108669243024;7846.725364612211;10162.491875406347;10314.484275786332;10354.275200735876;10417.531211095367;9526.333156678966;10633.043026127376;10653.786924261316;10655.627053053106;10685.679429617125;5827.504662103769;7309.122905251404;7652.426068045031;7837.059552853694;7926.207379262028;7197.424206063607;8513.702020429255;8520.61657225414;8525.573721314033;8603;7628.580428076509;7688.884666729891;7695.57674327895;7785.14363420012;7799.5320280783335;7584.203658615766;7619.352355049661;7646.426518011061;7653;7665.540067595962;7087.362137407658;7101.964490177723;7107.3603375694365;7168.498205125799;7203.991441198132;5021.548060674557;5036.722980236123;5100.132977393926;5107.565856902056;5191.29917461153;4875.391120930014;4952.653314268111;5024.171011783015;5055.69665820052;5169.172932330959 03:26:19 INFO - PROCESS | 1955 | |4;prototype.html;294.2775486181425;302.969703029697;315.04070106525637;319.4122814022199;321.2931550588704;4167.1457351243;4989.101961646911;5098.719570423626;5189.239943662532;5217.5912503623995;4409.382686423904;4675.579197872191;4972.726500042496;5111.6166287528495;5221.295125158103;3731.667707682698;3938.897108809531;3960.544537378195;3995.700322475826;3997.3004724173297;3798.54417469904;4017.4375587417785;4020.4170395292663;4029.214303210875;4029.2948733971584;3450.6894379505966;3785.299719551892;3812.9237415251678;3924.666403355718;3969.2260009298197;3601.279744051174;3923.4894565592454;3959.901252336252;4011.3581826907703;4014.437978682987;3474.3919814032442;3477.443609022544;3478.7564870459078;3500.649935006479;3511.5961664408665;4945.826896058621;4963.205887058053;5006.374203224597;5061.772220250097;5116.718580478072;3895.688344932427;4493.30353795159;4501.819927202908;4506.594406503397;4515.706479078849;4203.243416185085;5163.561097306733;5189.221616757479;5322.787088516455;5351.895900377811;3579.6599323064265;3615.331163734691;3720.8883733488037;3777.6033516480647;3804.923901521982;5430.262316058902;5632.746526406239;5705.62913410627;5740.425957404268;5765.971170144165;3773.3208022556205;3786.450964610171;3800.3539398302355;3803.1823158020607;3812.2375524895133;3888.183481468923;3894.805259737002;3904.590018048121;3919.4904662393706;3928.5464378180254;4050.3924411338535;4476.306172543261;4479.70881892676;4502.549745025472;4573.565511276423;4518.638508919278;5229.895402091937;5395.27538593008;5443.673379597237;5447.210154527571;1279.6097190356845;1308.587794844621;1309.9083064185415;1311.540960663779;1312.822768926202;760.7679657704454;763.9006929099181;797.2266901105918;815.8572249856252;817.7832874288318;3797;4028.5425890206952;4257.680673949404;4300.333448315516;4390.582894625006;4445.488768791565;4475.9705267788595;4715.4577223619035;4722.362481065081;4776.23580227162;3841.8079096045644;3855.207328592218;4022.431251811796;4192.727472714325;4233.830646774095;2159.751628562704;2202.592520383702;2406.1217655555947;2500.549901017834;2523.911663091783;3788.7726736395903;4211.852585159505;4216.8313267469575;4532.5694059064335;4594.724316541018;3991.5430867733066;4352.7823608819435;4696.929546056812;4713.033828065239;4741.359916411309;4131.360692654505;4602.205139995404;4729.344729344702;4808.619733232073;4872.51274872517;3923.666488348408;4186.225548273579;4480.327950807405;4668.719876807402;4672.5327467253;3845.9038524037123;4008.899777505469;4049.4162666041357;4072.735272207297;4454.821807127679;4493.820247190207;4772.737499437564;4783.976080119577;4909.828156014522;5008.4240312364545;3689.911476114499;3763.9247215056093;3774.7168962327387;3775.414810704345;3821.757928673248;4670.119724887433;4859.31969524274;4989.326440930428;5616.943830561642;5633.915491267552;4143.502779666459;4419.834380923927;4867.415910090812;5418.593605479684;5497.340319161726;4261.509926358384;5326.404676843038;5334.279872217201;5374.435684253253;5390.595705322038;5054.241863720325;5242.292290540729;5262.552683021986;5283.313169288122;5291.788328466818;5008.996401439454;5233.398159211587;5278.815241466684;5372.731363431891;5404.32445944257;5299.284596579567;5318.016167009116;5369.006733754268;5371.919421208763;5455.9454405455435 03:26:19 INFO - PROCESS | 1955 | |5;yui.html;727.5162017258523;741.3031750154857;778.3189709004621;827.2802661684335;829.6391069884601;15194.544163675093;16440.588985275375;16460.09469479178;16462.341506339733;16519.587010324747;14855.257237138127;15048.774268385983;15064.774028389556;15075.396984120622;15078.396864125449;690.2338404371144;709.3509438863447;713.596817797945;715.8138883890174;716.4662326566715;715.706560310273;719.7588807749411;859.1108203009854;890.8441022821013;892.6830975003877;930.8091841172577;942.4816351006953;942.7690215897114;943.7451887990238;946.9479178645172;938.9342746007782;939.3096074385321;940.7271891151557;941.0495399646338;941.7316064921464;697.5570512724406;699.0737273113094;700.9018737376771;701.4949236549676;704.0249254782112;12175.452104655224;12597.433115509824;12623.431945562472;12628.368581570885;12640.367981600975;11122.944385278102;14950.700985980327;15154.696906061927;15253.61865953349;15351.4626988056;11577.942110289394;11956.40217989097;12150.018492418154;12213.084018698633;12562.874371256263;3909.850444503918;3918.9804050979847;3976.880693579197;3982.183652351261;4104.938425923614;85723.57138214269;104917.47541262244;114692.72158041637;115164;119745;9787.608495660093;11694.883051169549;11749.29504229749;11777.293362398283;12058.698532536582;22519.099236030375;23618.29145125649;23620.291391257946;23623.527529449657;23630.7636923632;8197.631106600098;8243.505389676639;8280.420370574122;8386.622601982925;8512.574371281411;4475.776211189493;4495.43807024122;4539.795709193094;4601.562851529099;4645.001324715135;3389.1527118220447;3522.542069530996;3522.5772907251294;3524.3656141894703;3531.7704349217734;528.648448781556;648.6918713611092;657.2966925389787;657.5167252069725;657.8289644692414;590.5098768022531;622.7415622516605;658.2989116590911;674.8076798112514;683.8974153876958;9461.385009974467;9471.816022997125;9957.452340121363;10550.577976880992;10622.415767132727;610.920580324555;662.6786008785735;679.8198477403492;684.9520533562603;697.3723648716094;3850.3773509563507;4770.141374552614;4793.520647935178;4810.374651295309;4886.828960986349;90249.54875225713;128783;148785;154169.22915385352;165422.1728891348;92736.68158296097;132409.33795330962;135389;137296.6270337304;137413.3129334347;77508.61245693735;102757.48621256846;113114;117242;119834.20248696276;9042.367034307536;9192.172704456363;9215.953920230357;9218.585163667785;9226.123518265755;60935.69532152311;60950.695246523486;61265;63390;65915;87957.1204287949;95755;98720;100614;100917;11826.172167948162;11942.34317112567;12150.513979440724;12445.315507647167;12694.428750706425;11638.418079095842;13559.525416610371;14140.363683634467;14169.22069286199;14255.501057463354;10985.791562927912;12212.57255996036;12656.620301390973;12788.104832661624;13035.15271507349;11348.381951565165;11478.770424591294;12748.872511274769;13099.738005239651;13124.868751312364;10117.645882394376;11160.10719142483;11701.239419437708;11836.763264734485;12088.456019478968;10303.896961030294;10332.586696532055;10422;10688.766516542537;10697.786044279226;8838.513881736566;9246.815063698823;9576.138147566753;10925.672229833117;10984.505697243803 03:26:19 INFO - PROCESS | 1955 | __end_tp_report 03:26:19 INFO - PROCESS | 1955 | __start_cc_report 03:26:19 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1224 03:26:19 INFO - PROCESS | 1955 | __end_cc_report 03:26:19 INFO - PROCESS | 1955 | __startTimestamp1469874379775__endTimestamp 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | ------- Summary: start ------- 03:26:19 INFO - PROCESS | 1955 | Number of tests: 6 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5755.76 Median:6017.79 stddev:650.55 (10.8%) stddev-sans-first:624.04 03:26:19 INFO - PROCESS | 1955 | Values: 3327.9 3337.1 3367.7 3375.8 3410.1 5556.8 5600.4 5676.6 5732.3 5757.1 5641.5 5673.0 5795.2 5796.7 5851.5 5128.9 5160.9 5185.3 5191.8 5276.2 5311.7 5316.4 5320.2 5333.8 5361.5 4394.7 4946.8 5118.8 5172.7 5176.5 5126.8 5134.7 5150.2 5205.1 5216.1 5086.0 5141.4 5170.0 5193.2 5251.8 5688.8 5707.1 5755.7 5768.8 5772.0 6113.3 6143.5 6210.9 6222.1 6274.9 6214.8 6246.4 6262.8 6293.6 6297.0 5647.3 5884.9 5890.2 5892.7 5897.1 6164.8 6244.2 6269.0 6274.7 6275.7 6125.6 6137.0 6149.0 6152.4 6155.0 6116.5 6163.4 6262.1 6265.4 6270.7 6115.3 6159.4 6194.3 6197.8 6257.7 6261.4 6261.7 6269.2 6271.3 6288.4 4937.8 4945.1 4946.8 4952.9 4983.5 4342.0 4357.3 4417.6 4438.9 4479.2 5777.4 5781.3 5783.5 5786.5 5861.1 6138.9 6262.5 6277.1 6280.7 6304.4 5643.8 5681.9 5704.9 5775.6 5775.9 5453.1 5458.5 5470.4 5478.0 5506.4 6316.3 6319.1 6352.4 6354.5 6362.0 6104.5 6171.7 6240.3 6259.3 6266.3 6293.7 6336.4 6344.3 6349.3 6349.3 6184.4 6213.2 6219.8 6220.5 6227.5 5561.3 6113.8 6166.8 6207.8 6280.6 6161.6 6168.0 6176.2 6182.3 6204.1 6140.9 6151.1 6154.4 6155.8 6157.6 5862.9 5963.6 6017.8 6029.2 6035.9 6135.5 6152.3 6173.0 6179.0 6185.4 5964.5 5992.5 6003.2 6006.8 6071.9 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:73912.97 Median:19369.17 stddev:162234.70 (837.6%) stddev-sans-first:162596.24 03:26:19 INFO - PROCESS | 1955 | Values: 523.1 530.4 535.5 551.6 552.5 30745.8 34314.7 40661.2 41878.0 44547.8 43205.8 43383.1 43444.1 43490.1 43515.8 9641.4 9828.2 9829.1 9838.3 9861.9 8701.1 9046.4 11691.4 11839.2 11865.6 11916.0 12042.4 12064.9 12068.8 12078.4 11935.7 12000.0 12012.0 12013.8 12015.5 11852.5 11917.2 11933.8 11935.0 11938.3 41222.4 41465.0 41477.0 41532.8 41565.2 3075.3 3416.5 3459.8 3460.2 3837.6 32189.5 32962.0 33443.8 33448.7 33579.5 3239.4 3327.5 3428.6 3436.0 3481.3 500715.0 501508.0 503875.0 505029.0 505789.0 51611.2 62533.7 62942.4 63051.0 63090.7 66324.0 66459.3 66490.3 66545.7 66574.7 15776.8 15985.6 15993.2 15999.9 16025.5 11075.3 11261.2 12304.6 12383.0 12390.4 15059.5 15394.7 15397.8 15398.5 17992.6 4910.3 6213.2 6332.9 6352.4 6354.6 969.7 1030.2 1060.1 1080.3 1103.4 30878.7 30903.5 31053.7 31438.0 31655.0 1131.8 1157.3 1161.8 1177.2 1185.0 15173.5 15767.3 15851.6 15913.7 16014.6 663964.0 666525.0 667770.0 670077.0 672397.0 52556.7 59613.4 60210.0 60366.7 65016.0 1394.2 1421.8 1444.4 1446.0 1447.0 31212.1 31930.0 32183.0 33018.0 33028.7 53021.7 57449.7 58949.7 59208.0 62446.1 631066.0 636673.0 637004.0 637248.0 637803.0 26641.9 27033.5 27040.2 27124.3 27134.5 48495.8 48561.5 48588.8 49991.3 50656.5 39963.8 40203.8 40524.3 41359.0 41694.8 35277.8 39602.0 39972.6 40100.8 41428.0 38902.2 39373.2 39758.0 39780.6 39799.8 26921.1 33585.7 33651.3 35257.0 36628.1 25851.9 26241.5 26877.3 27180.0 27599.4 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:35379.42 Median:362.36 stddev:104312.85 (28787.2%) stddev-sans-first:104572.30 03:26:19 INFO - PROCESS | 1955 | Values: 340.9 347.7 377.0 378.4 406.6 15482.8 16868.4 20992.5 21255.8 21321.8 16453.9 20308.0 21047.8 22391.7 22447.6 4422.9 5327.8 5333.9 5448.6 5452.0 5346.8 5426.9 5436.5 5441.9 5444.8 3946.9 5826.3 6053.6 6095.9 6140.7 6004.0 6044.6 6109.9 6114.2 6115.4 5646.6 5775.7 5783.0 5786.1 5786.8 10545.8 13078.7 13229.8 13392.5 13406.7 1847.9 1850.7 2024.9 2085.3 2275.8 10113.4 10160.9 10312.4 11380.5 16380.5 1784.4 1829.2 1867.4 1940.7 1943.4 104266.5 109177.5 111296.4 111572.0 113789.0 9465.0 11467.3 11512.8 11666.3 11683.3 14742.7 14767.6 14795.7 14796.2 14806.1 6716.8 8078.2 8229.8 8403.4 8417.1 7189.3 8026.3 8044.1 8193.9 8199.5 4497.6 4583.2 4594.3 4675.0 4681.2 1633.3 1893.9 1976.8 1990.2 1995.0 853.6 892.0 927.8 936.9 941.2 20440.1 21946.0 22010.6 22032.6 22327.0 801.6 865.0 910.9 929.9 965.0 11066.4 11308.0 11466.5 11486.3 11549.9 617103.0 620705.9 629160.0 631629.0 633172.0 86222.0 132011.0 138181.0 138520.3 142292.0 960.7 961.5 989.9 991.8 992.3 14629.9 17654.8 18228.5 19338.2 19573.3 53108.7 54117.0 54808.2 54895.0 58147.7 79404.6 82295.2 84546.6 84580.6 85563.6 7305.6 7577.2 8025.8 8209.5 8237.5 12589.7 13138.5 13335.7 13775.2 13777.9 11392.1 11546.5 11598.6 11718.9 11749.9 9047.0 11358.4 11491.3 11562.9 11773.7 9310.5 10428.2 11441.8 11582.9 11807.7 7800.8 7804.3 7836.5 7850.2 7996.0 7550.2 9568.9 11348.3 11625.5 11683.8 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5746.31 Median:4914.02 stddev:3299.70 (67.1%) stddev-sans-first:3292.60 03:26:19 INFO - PROCESS | 1955 | Values: 1374.9 1517.3 1558.7 1785.5 1964.4 8106.8 8484.4 8487.6 8490.8 8491.0 8663.9 8866.6 8938.3 9067.2 9074.8 2388.2 2419.3 2421.6 2423.9 2426.5 2355.2 2395.4 2399.2 2405.8 2408.3 2358.2 2363.7 2378.1 2393.6 2401.1 2320.6 2342.5 2350.2 2359.9 2371.1 2318.1 2348.6 2352.5 2352.5 2354.7 9034.7 9102.6 9126.3 9130.2 9152.4 5256.1 5266.8 5419.4 5423.8 5428.5 3498.7 3545.6 3548.8 3555.6 3562.0 1031.2 1042.8 1045.0 1058.7 1071.5 12041.1 12222.5 12229.4 12231.3 12236.6 3922.0 4537.7 4571.2 4624.2 4704.2 9513.5 9528.5 9535.1 9535.1 9547.9 5301.4 5309.2 5310.6 5314.3 5318.2 4108.9 4427.3 4557.8 4588.7 4954.6 3792.9 3819.0 3931.1 3935.1 3937.2 996.2 1105.9 1144.9 1163.7 1176.1 641.8 733.5 807.9 822.3 922.7 5747.3 5872.6 5874.6 5918.6 6018.7 425.4 434.1 438.4 946.3 1015.4 3775.3 3840.3 3894.7 4020.0 4021.5 10836.3 11094.6 11192.9 11209.2 11257.9 10362.3 10770.8 10972.0 10985.6 11177.6 8136.6 8330.5 8540.8 8544.9 8553.6 5101.6 5966.4 5994.4 6101.2 6147.1 7846.7 10162.5 10314.5 10354.3 10417.5 9526.3 10633.0 10653.8 10655.6 10685.7 5827.5 7309.1 7652.4 7837.1 7926.2 7197.4 8513.7 8520.6 8525.6 8603.0 7628.6 7688.9 7695.6 7785.1 7799.5 7584.2 7619.4 7646.4 7653.0 7665.5 7087.4 7102.0 7107.4 7168.5 7204.0 5021.5 5036.7 5100.1 5107.6 5191.3 4875.4 4952.7 5024.2 5055.7 5169.2 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4139.88 Median:4450.16 stddev:1229.79 (27.6%) stddev-sans-first:1198.89 03:26:19 INFO - PROCESS | 1955 | Values: 294.3 303.0 315.0 319.4 321.3 4167.1 4989.1 5098.7 5189.2 5217.6 4409.4 4675.6 4972.7 5111.6 5221.3 3731.7 3938.9 3960.5 3995.7 3997.3 3798.5 4017.4 4020.4 4029.2 4029.3 3450.7 3785.3 3812.9 3924.7 3969.2 3601.3 3923.5 3959.9 4011.4 4014.4 3474.4 3477.4 3478.8 3500.6 3511.6 4945.8 4963.2 5006.4 5061.8 5116.7 3895.7 4493.3 4501.8 4506.6 4515.7 4203.2 5163.6 5189.2 5322.8 5351.9 3579.7 3615.3 3720.9 3777.6 3804.9 5430.3 5632.7 5705.6 5740.4 5766.0 3773.3 3786.5 3800.4 3803.2 3812.2 3888.2 3894.8 3904.6 3919.5 3928.5 4050.4 4476.3 4479.7 4502.5 4573.6 4518.6 5229.9 5395.3 5443.7 5447.2 1279.6 1308.6 1309.9 1311.5 1312.8 760.8 763.9 797.2 815.9 817.8 3797.0 4028.5 4257.7 4300.3 4390.6 4445.5 4476.0 4715.5 4722.4 4776.2 3841.8 3855.2 4022.4 4192.7 4233.8 2159.8 2202.6 2406.1 2500.5 2523.9 3788.8 4211.9 4216.8 4532.6 4594.7 3991.5 4352.8 4696.9 4713.0 4741.4 4131.4 4602.2 4729.3 4808.6 4872.5 3923.7 4186.2 4480.3 4668.7 4672.5 3845.9 4008.9 4049.4 4072.7 4454.8 4493.8 4772.7 4784.0 4909.8 5008.4 3689.9 3763.9 3774.7 3775.4 3821.8 4670.1 4859.3 4989.3 5616.9 5633.9 4143.5 4419.8 4867.4 5418.6 5497.3 4261.5 5326.4 5334.3 5374.4 5390.6 5054.2 5242.3 5262.6 5283.3 5291.8 5009.0 5233.4 5278.8 5372.7 5404.3 5299.3 5318.0 5369.0 5371.9 5455.9 03:26:19 INFO - PROCESS | 1955 | 03:26:19 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:24340.11 Median:3691.07 stddev:38951.59 (1055.3%) stddev-sans-first:39020.51 03:26:19 INFO - PROCESS | 1955 | Values: 727.5 741.3 778.3 827.3 829.6 15194.5 16440.6 16460.1 16462.3 16519.6 14855.3 15048.8 15064.8 15075.4 15078.4 690.2 709.4 713.6 715.8 716.5 715.7 719.8 859.1 890.8 892.7 930.8 942.5 942.8 943.7 946.9 938.9 939.3 940.7 941.0 941.7 697.6 699.1 700.9 701.5 704.0 12175.5 12597.4 12623.4 12628.4 12640.4 11122.9 14950.7 15154.7 15253.6 15351.5 11577.9 11956.4 12150.0 12213.1 12562.9 3909.9 3919.0 3976.9 3982.2 4104.9 85723.6 104917.5 114692.7 115164.0 119745.0 9787.6 11694.9 11749.3 11777.3 12058.7 22519.1 23618.3 23620.3 23623.5 23630.8 8197.6 8243.5 8280.4 8386.6 8512.6 4475.8 4495.4 4539.8 4601.6 4645.0 3389.2 3522.5 3522.6 3524.4 3531.8 528.6 648.7 657.3 657.5 657.8 590.5 622.7 658.3 674.8 683.9 9461.4 9471.8 9957.5 10550.6 10622.4 610.9 662.7 679.8 685.0 697.4 3850.4 4770.1 4793.5 4810.4 4886.8 90249.5 128783.0 148785.0 154169.2 165422.2 92736.7 132409.3 135389.0 137296.6 137413.3 77508.6 102757.5 113114.0 117242.0 119834.2 9042.4 9192.2 9216.0 9218.6 9226.1 60935.7 60950.7 61265.0 63390.0 65915.0 87957.1 95755.0 98720.0 100614.0 100917.0 11826.2 11942.3 12150.5 12445.3 12694.4 11638.4 13559.5 14140.4 14169.2 14255.5 10985.8 12212.6 12656.6 12788.1 13035.2 11348.4 11478.8 12748.9 13099.7 13124.9 10117.6 11160.1 11701.2 11836.8 12088.5 10303.9 10332.6 10422.0 10688.8 10697.8 8838.5 9246.8 9576.1 10925.7 10984.5 03:26:19 INFO - PROCESS | 1955 | -------- Summary: end -------- 03:26:19 INFO - PROCESS | 1955 | 03:26:20 INFO - TEST-INFO | 1955: exit 0 03:26:20 INFO - TEST-OK | dromaeo_css | took 1116150ms 03:26:20 INFO - TEST-START | kraken 03:26:20 INFO - operating with platform_type : mac_ 03:26:20 INFO - Initialising browser for kraken test... 03:26:20 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmps80Qov/profile http://localhost:52107/getInfo.html) 03:26:22 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 03:26:22 INFO - PROCESS | 1960 | colorDepth:24 03:26:22 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 03:26:22 INFO - PROCESS | 1960 | __metrics 03:26:22 INFO - PROCESS | 1960 | 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:22 INFO - PROCESS | 1960 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:26:23 INFO - TEST-INFO | 1960: exit 0 03:26:23 INFO - Browser initialized. 03:26:23 INFO - Running cycle 1/1 for kraken test... 03:26:23 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmps80Qov/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:35 INFO - PROCESS | 1961 | RSS: Main: 243216384 03:26:35 INFO - PROCESS | 1961 | 03:26:38 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-beat-detection) 03:26:39 INFO - PROCESS | 1961 | RSS: Main: 323104768 03:26:39 INFO - PROCESS | 1961 | 03:26:42 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-dft) 03:26:42 INFO - PROCESS | 1961 | RSS: Main: 333828096 03:26:42 INFO - PROCESS | 1961 | 03:26:46 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-fft) 03:26:46 INFO - PROCESS | 1961 | RSS: Main: 428027904 03:26:46 INFO - PROCESS | 1961 | 03:26:48 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-oscillator) 03:26:49 INFO - PROCESS | 1961 | RSS: Main: 329760768 03:26:49 INFO - PROCESS | 1961 | 03:26:51 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:26:52 INFO - PROCESS | 1961 | RSS: Main: 334041088 03:26:52 INFO - PROCESS | 1961 | 03:26:54 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-darkroom) 03:26:55 INFO - PROCESS | 1961 | RSS: Main: 371257344 03:26:55 INFO - PROCESS | 1961 | 03:26:58 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-desaturate) 03:26:59 INFO - PROCESS | 1961 | RSS: Main: 373424128 03:26:59 INFO - PROCESS | 1961 | 03:27:02 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52107/tests/kraken/driver.html?testName=json-parse-financial) 03:27:02 INFO - PROCESS | 1961 | RSS: Main: 373055488 03:27:02 INFO - PROCESS | 1961 | 03:27:04 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:27:04 INFO - PROCESS | 1961 | RSS: Main: 346112000 03:27:04 INFO - PROCESS | 1961 | 03:27:06 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:27:07 INFO - PROCESS | 1961 | RSS: Main: 352247808 03:27:07 INFO - PROCESS | 1961 | 03:27:09 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:27:10 INFO - PROCESS | 1961 | RSS: Main: 358076416 03:27:10 INFO - PROCESS | 1961 | 03:27:13 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:27:13 INFO - PROCESS | 1961 | RSS: Main: 360853504 03:27:13 INFO - PROCESS | 1961 | 03:27:16 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:27:17 INFO - PROCESS | 1961 | RSS: Main: 362233856 03:27:17 INFO - PROCESS | 1961 | 03:27:18 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52107/tests/kraken/driver.html?testName=ai-astar) 03:27:19 INFO - PROCESS | 1961 | RSS: Main: 405639168 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | __start_tp_report 03:27:19 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 03:27:19 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 03:27:19 INFO - PROCESS | 1961 | |i|pagename|runs| 03:27:19 INFO - PROCESS | 1961 | |0;ai-astar;100;111;113;113;112;112;112;111;111;112 03:27:19 INFO - PROCESS | 1961 | |1;audio-beat-detection;143;115;107;162;106;106;130;110;177;140 03:27:19 INFO - PROCESS | 1961 | |2;audio-dft;186;179;185;185;181;183;181;184;182;183 03:27:19 INFO - PROCESS | 1961 | |3;audio-fft;78;59;59;107;58;60;62;63;85;57 03:27:19 INFO - PROCESS | 1961 | |4;audio-oscillator;84;68;92;71;79;68;99;70;68;102 03:27:19 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;87;84;89;84;88;84;88;84;86;92 03:27:19 INFO - PROCESS | 1961 | |6;imaging-darkroom;94;111;150;94;93;93;94;94;94;93 03:27:19 INFO - PROCESS | 1961 | |7;imaging-desaturate;94;90;93;91;94;89;92;91;95;84 03:27:19 INFO - PROCESS | 1961 | |8;json-parse-financial;55;53;52;53;53;56;52;53;55;53 03:27:19 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;67;76;78;77;74;72;77;72;72;73 03:27:19 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;85;86;85;83;85;85;84;85;85;101 03:27:19 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;141;140;137;136;139;136;136;137;150;139 03:27:19 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;167;167;167;165;167;166;166;167;166;165 03:27:19 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;69;66;67;66;67;65;64;63;64;66 03:27:19 INFO - PROCESS | 1961 | __end_tp_report 03:27:19 INFO - PROCESS | 1961 | __start_cc_report 03:27:19 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,2092 03:27:19 INFO - PROCESS | 1961 | __end_cc_report 03:27:19 INFO - PROCESS | 1961 | __startTimestamp1469874439221__endTimestamp 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | ------- Summary: start ------- 03:27:19 INFO - PROCESS | 1961 | Number of tests: 14 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.83 (3.4%) stddev-sans-first:0.78 03:27:19 INFO - PROCESS | 1961 | Values: 100.0 111.0 113.0 113.0 112.0 112.0 112.0 111.0 111.0 112.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:135.00 stddev:25.40 (18.8%) stddev-sans-first:26.47 03:27:19 INFO - PROCESS | 1961 | Values: 143.0 115.0 107.0 162.0 106.0 106.0 130.0 110.0 177.0 140.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:2.18 (1.2%) stddev-sans-first:2.01 03:27:19 INFO - PROCESS | 1961 | Values: 186.0 179.0 185.0 185.0 181.0 183.0 181.0 184.0 182.0 183.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:68.80 Median:61.00 stddev:16.37 (26.8%) stddev-sans-first:17.02 03:27:19 INFO - PROCESS | 1961 | Values: 78.0 59.0 59.0 107.0 58.0 60.0 62.0 63.0 85.0 57.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:75.00 stddev:13.41 (17.9%) stddev-sans-first:14.15 03:27:19 INFO - PROCESS | 1961 | Values: 84.0 68.0 92.0 71.0 79.0 68.0 99.0 70.0 68.0 102.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:2.72 (3.1%) stddev-sans-first:2.88 03:27:19 INFO - PROCESS | 1961 | Values: 87.0 84.0 89.0 84.0 88.0 84.0 88.0 84.0 86.0 92.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:94.00 stddev:18.07 (19.2%) stddev-sans-first:18.99 03:27:19 INFO - PROCESS | 1961 | Values: 94.0 111.0 150.0 94.0 93.0 93.0 94.0 94.0 94.0 93.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:92.50 stddev:3.20 (3.5%) stddev-sans-first:3.24 03:27:19 INFO - PROCESS | 1961 | Values: 94.0 90.0 93.0 91.0 94.0 89.0 92.0 91.0 95.0 84.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:1.35 (2.6%) stddev-sans-first:1.32 03:27:19 INFO - PROCESS | 1961 | Values: 55.0 53.0 52.0 53.0 53.0 56.0 52.0 53.0 55.0 53.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:75.00 stddev:3.33 (4.4%) stddev-sans-first:2.46 03:27:19 INFO - PROCESS | 1961 | Values: 67.0 76.0 78.0 77.0 74.0 72.0 77.0 72.0 72.0 73.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.00 stddev:5.19 (6.1%) stddev-sans-first:5.48 03:27:19 INFO - PROCESS | 1961 | Values: 85.0 86.0 85.0 83.0 85.0 85.0 84.0 85.0 85.0 101.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:139.00 stddev:4.23 (3.0%) stddev-sans-first:4.43 03:27:19 INFO - PROCESS | 1961 | Values: 141.0 140.0 137.0 136.0 139.0 136.0 136.0 137.0 150.0 139.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:167.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 03:27:19 INFO - PROCESS | 1961 | Values: 167.0 167.0 167.0 165.0 167.0 166.0 166.0 167.0 166.0 165.0 03:27:19 INFO - PROCESS | 1961 | 03:27:19 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.77 (2.7%) stddev-sans-first:1.41 03:27:19 INFO - PROCESS | 1961 | Values: 69.0 66.0 67.0 66.0 67.0 65.0 64.0 63.0 64.0 66.0 03:27:19 INFO - PROCESS | 1961 | -------- Summary: end -------- 03:27:19 INFO - PROCESS | 1961 | 03:27:20 INFO - TEST-INFO | 1961: exit 0 03:27:20 INFO - TEST-OK | kraken | took 59651ms 03:27:20 INFO - SUITE-END | took 1175s 03:27:20 INFO - Completed test suite (00:19:36) 03:27:20 INFO - javascript benchmark 03:27:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.9334413311735, 3337.098983274516, 3367.6632336766324, 3375.8312084395807, 3410.0963244740146, 5556.777728890839, 5600.439956004403, 5676.631018983763, 5732.312122545295, 5757.107648314507, 5641.464060914217, 5673.035583950728, 5795.1886735857015, 5796.652200867951, 5851.473367396933, 5128.871778205537, 5160.948390516103, 5185.325907632003, 5191.844244672666, 5276.15581506959, 5311.7344132793205, 5316.388615309231, 5320.17537281722, 5333.786648534054, 5361.463853614647, 4394.692371533978, 4946.7526623668855, 5118.769655365518, 5172.741362931876, 5176.50823171798, 5126.794928202868, 5134.691918484903, 5150.227465880148, 5205.089109405839, 5216.1393370094265, 5085.974570127126, 5141.383034035902, 5170.0, 5193.246979187997, 5251.842444726626, 5688.829335119953, 5707.14392841073, 5755.68343741094, 5768.826935191951, 5772.0, 6113.327533971304, 6143.539234557381, 6210.9068363974575, 6222.097795819626, 6274.874502509924, 6214.751409943597, 6246.375362463763, 6262.843428914268, 6293.559450838443, 6297.0, 5647.29408823897, 5884.941150588482, 5890.204822348971, 5892.734826932799, 5897.056470964625, 6164.753409863645, 6244.188255526752, 6268.968655156695, 6274.749010039547, 6275.654838983808, 6125.632462052291, 6136.969315153396, 6148.954677704801, 6152.384761523901, 6154.969225153845, 6116.548817443993, 6163.38366163389, 6262.091996660459, 6265.373462653698, 6270.717817698214, 6115.270764408881, 6159.353267906895, 6194.318624951342, 6197.81406557804, 6257.687115644199, 6261.373862613793, 6261.655608941461, 6269.216347956505, 6271.310155882849, 6288.434040936246, 4937.7777999990085, 4945.134601444733, 4946.777395017233, 4952.8514144575, 4983.501649834988, 4341.978290108529, 4357.259265924737, 4417.602415782595, 4438.889027774267, 4479.216137175981, 5777.393373695785, 5781.335146458211, 5783.537317014629, 5786.508146807484, 5861.062230043172, 6138.938610613836, 6262.530310226751, 6277.089821975788, 6280.685965701697, 6304.432601065926, 5643.802466913638, 5681.914771278434, 5704.914426283609, 5775.595708300379, 5775.913361299584, 5453.127499600045, 5458.45415458459, 5470.425605311385, 5477.986572484103, 5506.449355064461, 6316.336784637639, 6319.05214217871, 6352.36476352361, 6354.52341074412, 6361.968190159019, 6104.519788040365, 6171.722272497838, 6240.313565507699, 6259.3427690091885, 6266.310705822447, 6293.748250069946, 6336.39804218617, 6344.333844946214, 6349.269833969152, 6349.333320001333, 6184.381561843959, 6213.2233470816145, 6219.751209951552, 6220.533459990428, 6227.532935029801, 5561.277033985556, 6113.841195312776, 6166.81499555014, 6207.751689932533, 6280.560360774702, 6161.599496032743, 6168.013117901114, 6176.166217560752, 6182.28903676083, 6204.100405441095, 6140.9078863816185, 6151.138840562236, 6154.353792851686, 6155.815325540241, 6157.568970172045, 5862.912056319074, 5963.642181469126, 6017.789377371946, 6029.1559181715475, 6035.939640603538, 6135.509159267159, 6152.323244443197, 6173.043178307369, 6179.0, 6185.443310102112, 5964.493018093511, 5992.509363295901, 6003.249593800775, 6006.819795406145, 6071.908921366095], "value": 5712.824204194265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.1394356283913, 530.4483337329181, 535.4779090386874, 551.6083580657731, 552.5220684108248, 30745.84627076864, 34314.65685343146, 40661.186776264454, 41878.0, 44547.77726111373, 43205.78397108018, 43383.132337353316, 43444.131117377634, 43490.130197396036, 43515.782421087926, 9641.421514709105, 9828.164606008499, 9829.115379615832, 9838.311318207729, 9861.852072218922, 8701.08638592948, 9046.3667543272, 11691.356975366352, 11839.230450020765, 11865.584704535342, 11916.046716262677, 12042.397880106004, 12064.879351206464, 12068.758624827498, 12078.396080195998, 11935.701607459796, 12000.0, 12012.03903687703, 12013.759724805499, 12015.459304331325, 11852.525898964117, 11917.22538035025, 11933.761324773455, 11935.0, 11938.28370297785, 41222.38166427506, 41465.0, 41477.0, 41532.792336038125, 41565.1686966259, 3075.3080556874747, 3416.521686963827, 3459.775114617542, 3460.1522626956557, 3837.616238376168, 32189.51715724266, 32962.0, 33443.83278083594, 33448.6655133448, 33579.496307555404, 3239.3521295740948, 3327.517509961042, 3428.5714285714284, 3436.0035589678964, 3481.3385456763135, 500715.0, 501508.0, 503875.0, 505029.0, 505789.0, 51611.22583161256, 62533.68733156305, 62942.37057629457, 63051.0, 63090.68454657697, 66324.0, 66459.33540664532, 66490.33509664939, 66545.6672716643, 66574.66712666502, 15776.76334854978, 15985.600359991093, 15993.200339982954, 15999.920000399923, 16025.519234422985, 11075.335479871233, 11261.211715180023, 12304.630861074003, 12383.009359251238, 12390.442430090667, 15059.472918447802, 15394.692106157814, 15397.845161612924, 15398.46105386306, 17992.550186245186, 4910.29272898959, 6213.2233470816145, 6332.905006424908, 6352.428281454691, 6354.601987562729, 969.6896992962326, 1030.1552726763982, 1060.1359891688248, 1080.3301952789163, 1103.3986477369854, 30878.69121308803, 30903.536446953312, 31053.689463105533, 31438.0, 31655.0, 1131.7623299107279, 1157.288267715366, 1161.848959635242, 1177.205386364218, 1185.0045961392473, 15173.544793656209, 15767.29047192879, 15851.603709907344, 15913.68172636564, 16014.599635009217, 663964.0, 666525.0, 667770.0, 670077.0, 672397.0, 52556.737216313675, 59613.40386596078, 60210.0, 60366.69816650889, 65016.0, 1394.2401391241797, 1421.8364888037802, 1444.400573761877, 1445.9566213013627, 1446.978295325571, 31212.063638090895, 31930.0, 32183.0, 33018.009459716246, 33028.66971330256, 53021.73489132529, 57449.712751435974, 58949.70525147347, 59208.0, 62446.06330905131, 631066.0, 636673.0, 637004.0, 637248.0, 637803.0, 26641.86679066592, 27033.45933081288, 27040.1887943362, 27124.32189195207, 27134.457310854068, 48495.75752121217, 48561.51438485711, 48588.75705621449, 49991.25013124733, 50656.493435065175, 39963.80018100007, 40203.79898100491, 40524.25968448911, 41359.0, 41694.79152604339, 35277.82361088178, 39602.0, 39972.60027399689, 40100.799496002335, 41427.964300891515, 38902.22195556016, 39373.21253574855, 39758.0, 39780.60219397885, 39799.80100099481, 26921.057762978202, 33585.66414335923, 33651.326973460884, 35257.0, 36628.08429789277, 25851.870740646176, 26241.4751704961, 26877.328066797705, 27180.048698295464, 27599.44801104007], "value": 20816.23713250389, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.8534330237998, 347.7444078602225, 376.9736118471707, 378.35868203395245, 406.56091421265035, 15482.84517154828, 16868.409605663805, 20992.475188120305, 21255.787442125573, 21321.786782132174, 16453.917730411362, 20307.984600769978, 21047.789522104773, 22391.664125038136, 22447.55104897905, 4422.867313980586, 5327.813526526573, 5333.919991200135, 5448.591355648342, 5451.972740136293, 5346.8395948121615, 5426.945730542703, 5436.510714035736, 5441.863953401157, 5444.8366549003595, 3946.940795888064, 5826.300843898738, 6053.606515576495, 6095.939040609582, 6140.692965351737, 6004.0, 6044.637321760709, 6109.938900611004, 6114.205153330038, 6115.388461153893, 5646.576506761969, 5775.682337471438, 5783.0, 5786.0742281235225, 5786.768529258825, 10545.841812372744, 13078.673033174247, 13229.801552976713, 13392.531261405902, 13406.731865362639, 1847.898365589892, 1850.6576283387617, 2024.9190032398687, 2085.343116918166, 2275.7724227577273, 10113.393196408239, 10160.94919525405, 10312.432816195107, 11380.544778208945, 16380.508584742476, 1784.4200634793615, 1829.2134382215643, 1867.374429566097, 1940.7477027986272, 1943.446117856404, 104266.47866760618, 109177.45411272973, 111296.4435177827, 111572.0, 113789.0, 9464.95267523658, 11467.311961282183, 11512.827307590393, 11666.300021998708, 11683.299002059903, 14742.705145897022, 14767.630809229855, 14795.704085918222, 14796.18620975835, 14806.111633302036, 6716.7984960450285, 8078.192180781993, 8229.835403291901, 8403.41176117666, 8417.116202798617, 7189.2810718927685, 8026.277635012877, 8044.115147333789, 8193.918060819435, 8199.508029518129, 4497.617702495325, 4583.220852455024, 4594.287885377772, 4674.95325046752, 4681.157391669532, 1633.3303345628235, 1893.8977734714456, 1976.7859996803672, 1990.2238127130431, 1994.9501262468555, 853.6073406233203, 892.0, 927.8077670193769, 936.8501039833731, 941.1952780372683, 20440.053884149198, 21946.012429440714, 22010.559788804134, 22032.559348813254, 22326.995285212204, 801.5791709352636, 864.9534063782893, 910.8588168833842, 929.9023602521638, 964.9517524123906, 11066.39134847575, 11307.982281594695, 11466.541338346373, 11486.253393529394, 11549.942250288863, 617103.0, 620705.8964705147, 629160.0, 631629.0, 633172.0, 86222.0, 132011.0, 138181.0, 138520.30739846238, 142292.0, 960.6829746183603, 961.4663861556763, 989.8515222716507, 991.7669347703284, 992.3153024413132, 14629.92685036568, 17654.823451765318, 18228.544286392946, 19338.226470941005, 19573.25068542152, 53108.734456327475, 54117.0, 54808.177877332666, 54895.0, 58147.70926145342, 79404.60297698705, 82295.17704822875, 84546.57726711327, 84580.57709711412, 85563.5721821387, 7305.575412794454, 7577.242275772379, 8025.799355016171, 8209.507429554245, 8237.546934918637, 12589.748205035665, 13138.540151094665, 13335.733285334045, 13775.242361670204, 13777.862221377658, 11392.088632909514, 11546.480408381474, 11598.594049208237, 11718.945294923498, 11749.88250117488, 9047.0, 11358.375289359165, 11491.310521368745, 11562.88437115618, 11773.705657358634, 9310.487923164292, 10428.217883658604, 11441.828372574251, 11582.899624535656, 11807.704807379885, 7800.765977020698, 7804.297613214838, 7836.490628109154, 7850.214978502104, 7996.0, 7550.207228240955, 9568.904310956801, 11348.262362946381, 11625.476853541442, 11683.824742628698], "value": 9289.027092321972, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.931253437328, 1517.2792923361405, 1558.7272227360213, 1785.4554360919915, 1964.3615824856925, 8106.797330066751, 8484.448510846798, 8487.575621218944, 8490.78773030673, 8490.98108227012, 8663.870041949376, 8866.645334186625, 8938.329625278098, 9067.183953444188, 9074.77313067172, 2388.1641425501066, 2419.334682962182, 2421.6125419932814, 2423.9394015149587, 2426.502566973766, 2355.2463211772238, 2395.4370722880108, 2399.2322456813827, 2405.7594240575977, 2408.2534414331526, 2358.221786810357, 2363.6809030780882, 2378.143868207444, 2393.617021276596, 2401.0515846240723, 2320.6286994081033, 2342.508073304608, 2350.247920665388, 2359.8938047787806, 2371.1226846066893, 2318.14228735369, 2348.5889969255313, 2352.482453860148, 2352.5294941011693, 2354.6703461515403, 9034.728958131267, 9102.590383432762, 9126.26989840811, 9130.17828395441, 9152.359334846564, 5256.053910296145, 5266.815661451869, 5419.376771671269, 5423.810166644149, 5428.5385742211565, 3498.702610278129, 3545.5745310562897, 3548.7870727756417, 3555.5555555555557, 3562.0382496725997, 1031.1750599520428, 1042.7549327853867, 1044.958201671932, 1058.6506452870535, 1071.4803320389606, 12041.096917731205, 12222.511099556095, 12229.388530573524, 12231.266124032587, 12236.632901012896, 3921.9607803922167, 4537.727736335831, 4571.245744452194, 4624.190766615896, 4704.223803072455, 9513.476758778195, 9528.523573821281, 9535.094166054214, 9535.094166054214, 9547.856782148274, 5301.443348448434, 5309.230161626542, 5310.575153987672, 5314.335708036496, 5318.228856815817, 4108.876733697994, 4427.269500532376, 4557.840475583339, 4588.7246765194195, 4954.578860796801, 3792.905177370588, 3819.0, 3931.056546428894, 3935.0752573145287, 3937.192875460581, 996.2478328861663, 1105.894105894106, 1144.8797876223043, 1163.6858048326903, 1176.0944073063692, 641.7625478572958, 733.4645708632621, 807.858624740668, 822.2599660305654, 922.6540047482194, 5747.3103227612955, 5872.559558033165, 5874.5887787854435, 5918.644881307136, 6018.6990650467305, 425.3768229543733, 434.0949121082578, 438.39939283182247, 946.2571881073462, 1015.441507171053, 3775.2826962876966, 3840.308744425974, 3894.7078969077434, 4020.0, 4021.477207962946, 10836.295640783324, 11094.5562177512, 11192.944035279772, 11209.21535492524, 11257.943710281395, 10362.274640775073, 10770.838437423445, 10972.0, 10985.560577576967, 11177.552897884156, 8136.593170341459, 8330.50016998982, 8540.786480337916, 8544.914550854537, 8553.615087321086, 5101.642884998015, 5966.403359663999, 5994.400559943971, 6101.237345331833, 6147.108669243024, 7846.725364612211, 10162.491875406347, 10314.484275786332, 10354.275200735876, 10417.531211095367, 9526.333156678966, 10633.043026127376, 10653.786924261316, 10655.627053053106, 10685.679429617125, 5827.504662103769, 7309.122905251404, 7652.426068045031, 7837.059552853694, 7926.207379262028, 7197.424206063607, 8513.702020429255, 8520.61657225414, 8525.573721314033, 8603.0, 7628.580428076509, 7688.884666729891, 7695.57674327895, 7785.14363420012, 7799.5320280783335, 7584.203658615766, 7619.352355049661, 7646.426518011061, 7653.0, 7665.540067595962, 7087.362137407658, 7101.964490177723, 7107.3603375694365, 7168.498205125799, 7203.991441198132, 5021.548060674557, 5036.722980236123, 5100.132977393926, 5107.565856902056, 5191.29917461153, 4875.391120930014, 4952.653314268111, 5024.171011783015, 5055.69665820052, 5169.172932330959], "value": 4534.0485876779085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.2775486181425, 302.969703029697, 315.04070106525637, 319.4122814022199, 321.2931550588704, 4167.1457351243, 4989.101961646911, 5098.719570423626, 5189.239943662532, 5217.5912503623995, 4409.382686423904, 4675.579197872191, 4972.726500042496, 5111.6166287528495, 5221.295125158103, 3731.667707682698, 3938.897108809531, 3960.544537378195, 3995.700322475826, 3997.3004724173297, 3798.54417469904, 4017.4375587417785, 4020.4170395292663, 4029.214303210875, 4029.2948733971584, 3450.6894379505966, 3785.299719551892, 3812.9237415251678, 3924.666403355718, 3969.2260009298197, 3601.279744051174, 3923.4894565592454, 3959.901252336252, 4011.3581826907703, 4014.437978682987, 3474.3919814032442, 3477.443609022544, 3478.7564870459078, 3500.649935006479, 3511.5961664408665, 4945.826896058621, 4963.205887058053, 5006.374203224597, 5061.772220250097, 5116.718580478072, 3895.688344932427, 4493.30353795159, 4501.819927202908, 4506.594406503397, 4515.706479078849, 4203.243416185085, 5163.561097306733, 5189.221616757479, 5322.787088516455, 5351.895900377811, 3579.6599323064265, 3615.331163734691, 3720.8883733488037, 3777.6033516480647, 3804.923901521982, 5430.262316058902, 5632.746526406239, 5705.62913410627, 5740.425957404268, 5765.971170144165, 3773.3208022556205, 3786.450964610171, 3800.3539398302355, 3803.1823158020607, 3812.2375524895133, 3888.183481468923, 3894.805259737002, 3904.590018048121, 3919.4904662393706, 3928.5464378180254, 4050.3924411338535, 4476.306172543261, 4479.70881892676, 4502.549745025472, 4573.565511276423, 4518.638508919278, 5229.895402091937, 5395.27538593008, 5443.673379597237, 5447.210154527571, 1279.6097190356845, 1308.587794844621, 1309.9083064185415, 1311.540960663779, 1312.822768926202, 760.7679657704454, 763.9006929099181, 797.2266901105918, 815.8572249856252, 817.7832874288318, 3797.0, 4028.5425890206952, 4257.680673949404, 4300.333448315516, 4390.582894625006, 4445.488768791565, 4475.9705267788595, 4715.4577223619035, 4722.362481065081, 4776.23580227162, 3841.8079096045644, 3855.207328592218, 4022.431251811796, 4192.727472714325, 4233.830646774095, 2159.751628562704, 2202.592520383702, 2406.1217655555947, 2500.549901017834, 2523.911663091783, 3788.7726736395903, 4211.852585159505, 4216.8313267469575, 4532.5694059064335, 4594.724316541018, 3991.5430867733066, 4352.7823608819435, 4696.929546056812, 4713.033828065239, 4741.359916411309, 4131.360692654505, 4602.205139995404, 4729.344729344702, 4808.619733232073, 4872.51274872517, 3923.666488348408, 4186.225548273579, 4480.327950807405, 4668.719876807402, 4672.5327467253, 3845.9038524037123, 4008.899777505469, 4049.4162666041357, 4072.735272207297, 4454.821807127679, 4493.820247190207, 4772.737499437564, 4783.976080119577, 4909.828156014522, 5008.4240312364545, 3689.911476114499, 3763.9247215056093, 3774.7168962327387, 3775.414810704345, 3821.757928673248, 4670.119724887433, 4859.31969524274, 4989.326440930428, 5616.943830561642, 5633.915491267552, 4143.502779666459, 4419.834380923927, 4867.415910090812, 5418.593605479684, 5497.340319161726, 4261.509926358384, 5326.404676843038, 5334.279872217201, 5374.435684253253, 5390.595705322038, 5054.241863720325, 5242.292290540729, 5262.552683021986, 5283.313169288122, 5291.788328466818, 5008.996401439454, 5233.398159211587, 5278.815241466684, 5372.731363431891, 5404.32445944257, 5299.284596579567, 5318.016167009116, 5369.006733754268, 5371.919421208763, 5455.9454405455435], "value": 3756.631683711404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.5162017258523, 741.3031750154857, 778.3189709004621, 827.2802661684335, 829.6391069884601, 15194.544163675093, 16440.588985275375, 16460.09469479178, 16462.341506339733, 16519.587010324747, 14855.257237138127, 15048.774268385983, 15064.774028389556, 15075.396984120622, 15078.396864125449, 690.2338404371144, 709.3509438863447, 713.596817797945, 715.8138883890174, 716.4662326566715, 715.706560310273, 719.7588807749411, 859.1108203009854, 890.8441022821013, 892.6830975003877, 930.8091841172577, 942.4816351006953, 942.7690215897114, 943.7451887990238, 946.9479178645172, 938.9342746007782, 939.3096074385321, 940.7271891151557, 941.0495399646338, 941.7316064921464, 697.5570512724406, 699.0737273113094, 700.9018737376771, 701.4949236549676, 704.0249254782112, 12175.452104655224, 12597.433115509824, 12623.431945562472, 12628.368581570885, 12640.367981600975, 11122.944385278102, 14950.700985980327, 15154.696906061927, 15253.61865953349, 15351.4626988056, 11577.942110289394, 11956.40217989097, 12150.018492418154, 12213.084018698633, 12562.874371256263, 3909.850444503918, 3918.9804050979847, 3976.880693579197, 3982.183652351261, 4104.938425923614, 85723.57138214269, 104917.47541262244, 114692.72158041637, 115164.0, 119745.0, 9787.608495660093, 11694.883051169549, 11749.29504229749, 11777.293362398283, 12058.698532536582, 22519.099236030375, 23618.29145125649, 23620.291391257946, 23623.527529449657, 23630.7636923632, 8197.631106600098, 8243.505389676639, 8280.420370574122, 8386.622601982925, 8512.574371281411, 4475.776211189493, 4495.43807024122, 4539.795709193094, 4601.562851529099, 4645.001324715135, 3389.1527118220447, 3522.542069530996, 3522.5772907251294, 3524.3656141894703, 3531.7704349217734, 528.648448781556, 648.6918713611092, 657.2966925389787, 657.5167252069725, 657.8289644692414, 590.5098768022531, 622.7415622516605, 658.2989116590911, 674.8076798112514, 683.8974153876958, 9461.385009974467, 9471.816022997125, 9957.452340121363, 10550.577976880992, 10622.415767132727, 610.920580324555, 662.6786008785735, 679.8198477403492, 684.9520533562603, 697.3723648716094, 3850.3773509563507, 4770.141374552614, 4793.520647935178, 4810.374651295309, 4886.828960986349, 90249.54875225713, 128783.0, 148785.0, 154169.22915385352, 165422.1728891348, 92736.68158296097, 132409.33795330962, 135389.0, 137296.6270337304, 137413.3129334347, 77508.61245693735, 102757.48621256846, 113114.0, 117242.0, 119834.20248696276, 9042.367034307536, 9192.172704456363, 9215.953920230357, 9218.585163667785, 9226.123518265755, 60935.69532152311, 60950.695246523486, 61265.0, 63390.0, 65915.0, 87957.1204287949, 95755.0, 98720.0, 100614.0, 100917.0, 11826.172167948162, 11942.34317112567, 12150.513979440724, 12445.315507647167, 12694.428750706425, 11638.418079095842, 13559.525416610371, 14140.363683634467, 14169.22069286199, 14255.501057463354, 10985.791562927912, 12212.57255996036, 12656.620301390973, 12788.104832661624, 13035.15271507349, 11348.381951565165, 11478.770424591294, 12748.872511274769, 13099.738005239651, 13124.868751312364, 10117.645882394376, 11160.10719142483, 11701.239419437708, 11836.763264734485, 12088.456019478968, 10303.896961030294, 10332.586696532055, 10422.0, 10688.766516542537, 10697.786044279226, 8838.513881736566, 9246.815063698823, 9576.138147566753, 10925.672229833117, 10984.505697243803], "value": 7715.589716258314, "unit": "score"}], "name": "dromaeo_css", "value": 7249.724734294278, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 113.0, 113.0, 112.0, 112.0, 112.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 107.0, 162.0, 106.0, 106.0, 130.0, 110.0, 177.0, 140.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 179.0, 185.0, 185.0, 181.0, 183.0, 181.0, 184.0, 182.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 107.0, 58.0, 60.0, 62.0, 63.0, 85.0, 57.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 92.0, 71.0, 79.0, 68.0, 99.0, 70.0, 68.0, 102.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 89.0, 84.0, 88.0, 84.0, 88.0, 84.0, 86.0, 92.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 111.0, 150.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 93.0, 91.0, 94.0, 89.0, 92.0, 91.0, 95.0, 84.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 53.0, 56.0, 52.0, 53.0, 55.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 78.0, 77.0, 74.0, 72.0, 77.0, 72.0, 72.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 83.0, 85.0, 85.0, 84.0, 85.0, 85.0, 101.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 137.0, 136.0, 139.0, 136.0, 136.0, 137.0, 150.0, 139.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 167.0, 165.0, 167.0, 166.0, 166.0, 167.0, 166.0, 165.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 66.0, 67.0, 65.0, 64.0, 63.0, 64.0, 66.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1435.8, "alertThreshold": 2.0}]} 03:27:20 INFO - Return code: 0 03:27:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:27:20 INFO - # TBPL SUCCESS # 03:27:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:27:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:27:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:27:20 INFO - 2016-07-30 03:27:20.522 screenresolution[1965:20514] starting screenresolution argv=screenresolution get 03:27:20 INFO - 2016-07-30 03:27:20.524 screenresolution[1965:20514] Display 0: 1600x1200x32@60 03:27:20 INFO - 2016-07-30 03:27:20.530 screenresolution[1966:20517] starting screenresolution argv=screenresolution list 03:27:20 INFO - Available Modes on Display 0 03:27:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:27:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:27:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:27:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:27:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:27:20 INFO - Intel Iris: 03:27:20 INFO - Chipset Model: Intel Iris 03:27:20 INFO - Type: GPU 03:27:20 INFO - Bus: Built-In 03:27:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:27:20 INFO - Vendor: Intel (0x8086) 03:27:20 INFO - Device ID: 0x0a2e 03:27:20 INFO - Revision ID: 0x0009 03:27:20 INFO - Displays: 03:27:20 INFO - Display: 03:27:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:27:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:27:20 INFO - Display Serial Number: 1600x1200 60 03:27:20 INFO - Main Display: Yes 03:27:20 INFO - Mirror: Off 03:27:20 INFO - Online: Yes 03:27:20 INFO - Rotation: Supported 03:27:20 INFO - Return code: 0 03:27:20 INFO - Running post-action listener: _resource_record_post_action 03:27:20 INFO - [mozharness: 2016-07-30 10:27:20.771336Z] Finished run-tests step (success) 03:27:20 INFO - Running post-run listener: _resource_record_post_run 03:27:20 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 724992; Write bytes: 705674752; Read time: 8; Write time: 1900 03:27:20 INFO - TinderboxPrint: CPU usage
53.5% 03:27:20 INFO - TinderboxPrint: I/O read bytes / time
724,992 / 8 03:27:20 INFO - TinderboxPrint: I/O write bytes / time
705,674,752 / 1,900 03:27:20 INFO - TinderboxPrint: CPU idle
2,251.3 (46.5%) 03:27:20 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 03:27:20 INFO - TinderboxPrint: CPU user
1,623.6 (33.5%) 03:27:20 INFO - TinderboxPrint: Swap in / out
405,118,976 / 0 03:27:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405613056; Write bytes: 409348096; Read time: 30084; Write time: 822 03:27:20 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 659456; Write bytes: 294938112; Read time: 6; Write time: 1068 03:27:21 INFO - Running post-run listener: _upload_blobber_files 03:27:21 INFO - Blob upload gear active. 03:27:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:27:21 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:21 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:21 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:21 INFO - (blobuploader) - INFO - Open directory for files ... 03:27:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:27:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: 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 - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:27:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:27:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:23 INFO - (blobuploader) - INFO - Done attempting. 03:27:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:27:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:27:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:26 INFO - (blobuploader) - INFO - Done attempting. 03:27:26 INFO - (blobuploader) - INFO - Iteration through files over. 03:27:26 INFO - Return code: 0 03:27:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:27:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:27:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e076304caa9f7601c5017a6937416b5b7ba117116fa552145cc228039db63a926f24f1fd216fcde87dc161adaad625660183b233386c60d6587788ce04e99369", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc8366d321cae051af40a49b8fd88f8694f46bd4e757d04ff8c107066beb95c231eccfdd3d4264928cadda18c55821fcb35ef2a7ccd700e4e05195582b0f31e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b93f68bb0def5b2e03c8d4655f91b247d6f34a3f139bec139c014235ed95ebf76bd15794b7e482521f9a86cf4158ed296775d6bb97063b94383cf4af08e040b"} 03:27:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:27:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:27:26 INFO - Contents: 03:27:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e076304caa9f7601c5017a6937416b5b7ba117116fa552145cc228039db63a926f24f1fd216fcde87dc161adaad625660183b233386c60d6587788ce04e99369", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc8366d321cae051af40a49b8fd88f8694f46bd4e757d04ff8c107066beb95c231eccfdd3d4264928cadda18c55821fcb35ef2a7ccd700e4e05195582b0f31e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b93f68bb0def5b2e03c8d4655f91b247d6f34a3f139bec139c014235ed95ebf76bd15794b7e482521f9a86cf4158ed296775d6bb97063b94383cf4af08e040b"} 03:27:26 INFO - Running post-run listener: copy_logs_to_upload_dir 03:27:26 INFO - Copying logs to upload dir... 03:27:26 INFO - mkdir: /builds/slave/test/build/upload/logs 03:27:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.023683 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-30 03:27:26.654197) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:26.657287) ========= 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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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/e076304caa9f7601c5017a6937416b5b7ba117116fa552145cc228039db63a926f24f1fd216fcde87dc161adaad625660183b233386c60d6587788ce04e99369", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc8366d321cae051af40a49b8fd88f8694f46bd4e757d04ff8c107066beb95c231eccfdd3d4264928cadda18c55821fcb35ef2a7ccd700e4e05195582b0f31e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b93f68bb0def5b2e03c8d4655f91b247d6f34a3f139bec139c014235ed95ebf76bd15794b7e482521f9a86cf4158ed296775d6bb97063b94383cf4af08e040b"} 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.011080 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/e076304caa9f7601c5017a6937416b5b7ba117116fa552145cc228039db63a926f24f1fd216fcde87dc161adaad625660183b233386c60d6587788ce04e99369", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc8366d321cae051af40a49b8fd88f8694f46bd4e757d04ff8c107066beb95c231eccfdd3d4264928cadda18c55821fcb35ef2a7ccd700e4e05195582b0f31e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b93f68bb0def5b2e03c8d4655f91b247d6f34a3f139bec139c014235ed95ebf76bd15794b7e482521f9a86cf4158ed296775d6bb97063b94383cf4af08e040b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:26.698276) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:26.698968) ========= 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.x0LSlXoNff/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.54WSal5sVH/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005355 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:26.753411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:27:26.754166) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:27:26.757118) ========= ========= Total master_lag: 0.37 =========