builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0274 starttime: 1469873176.55 results: success (0) buildid: 20160730030554 builduid: a1e84c7626874666a42e4d4c9d793da1 revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.549895) ========= 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.550373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.550853) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.650086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:16.650376) ========= 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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.5M=0.001s 2016-07-30 03:06:17 (19.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.612374 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.289206) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.289536) ========= 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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.025096 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:17.340731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:06:17.341717) ========= 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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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,427 truncating revision to first 12 chars 2016-07-30 03:06:17,427 Setting DEBUG logging. 2016-07-30 03:06:17,427 attempt 1/10 2016-07-30 03:06:17,427 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,537 unpacking tar archive at: fx-team-c3565c8b1cdb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.391292 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:06:18.756952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.757279) ========= script_repo_revision: c3565c8b1cdb575db1c80c7791984a6490598b84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.758161) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.758472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:06:18.776338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-07-30 03:06:18.776666) ========= /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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-0274', 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.972319Z] 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:21 INFO - Running post-action listener: _resource_record_post_action 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.021144Z] Finished clobber step (success) 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.021290Z] Running read-buildbot-config step. 03:06:21 INFO - Running pre-action listener: _resource_record_pre_action 03:06:21 INFO - Running main action method: read_buildbot_config 03:06:21 INFO - Using buildbot properties: 03:06:21 INFO - { 03:06:21 INFO - "project": "", 03:06:21 INFO - "product": "firefox", 03:06:21 INFO - "script_repo_revision": "production", 03:06:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:06:21 INFO - "repository": "", 03:06:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:06:21 INFO - "buildid": "20160730030554", 03:06:21 INFO - "stage_platform": "macosx64", 03:06:21 INFO - "basedir": "/builds/slave/test", 03:06:21 INFO - "buildnumber": 115, 03:06:21 INFO - "platform": "yosemite_r7", 03:06:21 INFO - "mozci_request": { 03:06:21 INFO - "priority": -1, 03:06:21 INFO - "type": "trigger_all_talos_jobs", 03:06:21 INFO - "times": 6 03:06:21 INFO - }, 03:06:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:06:21 INFO - "slavebuilddir": "test", 03:06:21 INFO - "branch": "fx-team", 03:06:21 INFO - "revision": "c3565c8b1cdb575db1c80c7791984a6490598b84", 03:06:21 INFO - "slavename": "t-yosemite-r7-0274", 03:06:21 INFO - "builduid": "a1e84c7626874666a42e4d4c9d793da1", 03:06:21 INFO - "repo_path": "integration/fx-team" 03:06:21 INFO - } 03:06:21 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.dmg. 03:06:21 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json. 03:06:21 INFO - Running post-action listener: _resource_record_post_action 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.025233Z] Finished read-buildbot-config step (success) 03:06:21 INFO - [mozharness: 2016-07-30 10:06:21.025322Z] Running download-and-extract step. 03:06:21 INFO - Running pre-action listener: _resource_record_pre_action 03:06:21 INFO - Running main action method: download_and_extract 03:06:21 INFO - mkdir: /builds/slave/test/build/tests 03:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 03:06:21 INFO - Downloaded 1199 bytes. 03:06:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:06:21 INFO - Using the following test package requirements: 03:06:21 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:06:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:06:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'jsshell-mac.zip'], 03:06:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:06:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:06:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:06:21 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:06:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:06:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:06:21 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:06:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:06:21 INFO - Downloaded 16300348 bytes. 03:06:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:06:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:06:24 INFO - Return code: 0 03:06:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1469865025/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:06:24 INFO - Downloaded 16300348 bytes. 03:06:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:06:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:06: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.811800Z] Finished download-and-extract step (success) 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.811934Z] 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.832530Z] Finished populate-webroot step (success) 03:06:38 INFO - [mozharness: 2016-07-30 10:06:38.832657Z] 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:06:42 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:06:42 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:06:46 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:06:47 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:06:50 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:06:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:53 INFO - Downloading 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:53 INFO - Successfully installed jsonschema functools32 03:06:53 INFO - Cleaning up... 03:06:53 INFO - Return code: 0 03:06:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:06:53 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:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:53 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:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:53 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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:53 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:53 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:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QveWl4GVis/Render', 03:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:53 INFO - 'HOME': '/Users/cltbld', 03:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:53 INFO - 'LOGNAME': 'cltbld', 03:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:53 INFO - 'MOZ_NO_REMOTE': '1', 03:06:53 INFO - 'NO_EM_RESTART': '1', 03:06:53 INFO - 'PAGER': '/bin/cat', 03:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:53 INFO - 'PWD': '/builds/slave/test', 03:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:53 INFO - 'SHELL': '/bin/bash', 03:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEvQTtmeX9/Listeners', 03:06:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 03:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:53 INFO - 'USER': 'cltbld', 03:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:53 INFO - 'XPC_FLAGS': '0x0', 03:06:53 INFO - 'XPC_SERVICE_NAME': '0', 03:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:06:53 INFO - Cleaning up... 03:06:53 INFO - Return code: 0 03:06:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:06:53 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:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:53 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:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:53 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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:53 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:53 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:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QveWl4GVis/Render', 03:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:53 INFO - 'HOME': '/Users/cltbld', 03:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:53 INFO - 'LOGNAME': 'cltbld', 03:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:53 INFO - 'MOZ_NO_REMOTE': '1', 03:06:53 INFO - 'NO_EM_RESTART': '1', 03:06:53 INFO - 'PAGER': '/bin/cat', 03:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:53 INFO - 'PWD': '/builds/slave/test', 03:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:53 INFO - 'SHELL': '/bin/bash', 03:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEvQTtmeX9/Listeners', 03:06:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 03:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:53 INFO - 'USER': 'cltbld', 03:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:53 INFO - 'XPC_FLAGS': '0x0', 03:06:53 INFO - 'XPC_SERVICE_NAME': '0', 03:06:53 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:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:06:56 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:56 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 - 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:56 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:56 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:56 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:57 INFO - Downloading docopt-0.6.1.tar.gz 03:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:06:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:06:57 INFO - Running setup.py install for blobuploader 03:06:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:06:57 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:58 INFO - Successfully installed blobuploader requests docopt 03:06:58 INFO - Cleaning up... 03:06:58 INFO - Return code: 0 03:06:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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:58 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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QveWl4GVis/Render', 03:06:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:58 INFO - 'HOME': '/Users/cltbld', 03:06:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:58 INFO - 'LOGNAME': 'cltbld', 03:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:58 INFO - 'MOZ_NO_REMOTE': '1', 03:06:58 INFO - 'NO_EM_RESTART': '1', 03:06:58 INFO - 'PAGER': '/bin/cat', 03:06:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:58 INFO - 'PWD': '/builds/slave/test', 03:06:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:58 INFO - 'SHELL': '/bin/bash', 03:06:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEvQTtmeX9/Listeners', 03:06:58 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 03:06:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:58 INFO - 'USER': 'cltbld', 03:06:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:58 INFO - 'XPC_FLAGS': '0x0', 03:06:58 INFO - 'XPC_SERVICE_NAME': '0', 03:06:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:06:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-juzVf6-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5bIUFm-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-UnZT3g-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-8rWnFO-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ukHHAl-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-1nPxMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:06:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-y7ZJaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:06:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-O0a5XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:06:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Xg5IDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:06:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Kr1muF-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-qi7Y3Y-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ve6L1o-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DUJHSO-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yVEB1C-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rstofD-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-zRlj0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:07:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-K4OBH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:07:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:07:00 INFO - Running setup.py install for manifestparser 03:07:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:07:00 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:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Running setup.py install for mozinfo 03:07:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Running setup.py install for mozInstall 03:07:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:07:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:07:01 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:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:07:02 INFO - Running setup.py install for mozrunner 03:07:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:07:02 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:07:03 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:07:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-85EfI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:07:03 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:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:07:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-55_Meq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:07:03 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:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:07:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ZscoEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:07:03 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:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:07:03 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-wlnnn0-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7bKffz-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Ao8Yz3-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-3P8avI-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DckQ6z-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-X5_QSt-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-mfGubD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:07:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:07:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gsgHGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:07:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:07:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-g_lfIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:07:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:07:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-m0oJPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:07:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:07:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-UBlmhK-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-t9ZOvw-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-TFlMM2-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/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-1KxpHx-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:07 INFO - Downloading blessings-1.6.tar.gz 03:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:07:07 INFO - Installing collected packages: blessings 03:07:07 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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:07:08 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:08 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:08 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:08 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:08 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:08 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:08 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:08 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:08 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:08 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:08 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:08 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:08 INFO - Cleaning up... 03:07:08 INFO - Return code: 0 03:07:08 INFO - Installing jsonschema 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', '--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 0x10b197a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b22c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdf1488470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, '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.QveWl4GVis/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.iEvQTtmeX9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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', '--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:08 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:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QveWl4GVis/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.iEvQTtmeX9/Listeners', 03:07:08 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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): 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.081136Z] Finished create-virtualenv step (success) 03:07:09 INFO - [mozharness: 2016-07-30 10:07:09.082085Z] 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:44 INFO - Reading from file tmpfile_stdout 03:07:44 INFO - Output received: 03:07:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:07:44 INFO - Running post-action listener: _resource_record_post_action 03:07:44 INFO - [mozharness: 2016-07-30 10:07:44.878869Z] Finished install step (success) 03:07:44 INFO - [mozharness: 2016-07-30 10:07:44.878997Z] Running run-tests step. 03:07:44 INFO - Running pre-action listener: _resource_record_pre_action 03:07:44 INFO - Running main action method: run_tests 03:07:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:44 INFO - 2016-07-30 03:07:44.893 screenresolution[1653:9781] starting screenresolution argv=screenresolution get 03:07:44 INFO - 2016-07-30 03:07:44.896 screenresolution[1653:9781] Display 0: 1600x1200x32@60 03:07:44 INFO - 2016-07-30 03:07:44.901 screenresolution[1654:9783] starting screenresolution argv=screenresolution list 03:07:44 INFO - Available Modes on Display 0 03:07:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:45 INFO - Intel Iris: 03:07:45 INFO - Chipset Model: Intel Iris 03:07:45 INFO - Type: GPU 03:07:45 INFO - Bus: Built-In 03:07:45 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:45 INFO - Vendor: Intel (0x8086) 03:07:45 INFO - Device ID: 0x0a2e 03:07:45 INFO - Revision ID: 0x0009 03:07:45 INFO - Displays: 03:07:45 INFO - Display: 03:07:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:45 INFO - Display Serial Number: 1600x1200 60 03:07:45 INFO - Main Display: Yes 03:07:45 INFO - Mirror: Off 03:07:45 INFO - Online: Yes 03:07:45 INFO - Rotation: Supported 03:07:45 INFO - Return code: 0 03:07:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:07:45 INFO - Python 2.7.3 03:07:45 INFO - Return code: 0 03:07:45 INFO - grabbing minidump binary from tooltool 03:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:07:45 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 0x10b0fbd30>, '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 0x10ae60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:07:45 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:45 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:45 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:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:07:45 INFO - Return code: 0 03:07:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:07:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:07:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:07:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:07:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:07:45 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-0274', '--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:45 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-0274 --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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QveWl4GVis/Render', 03:07:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:07:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:07:45 INFO - 'HOME': '/Users/cltbld', 03:07:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:07:45 INFO - 'LOGNAME': 'cltbld', 03:07:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:07:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:45 INFO - 'MOZ_NO_REMOTE': '1', 03:07:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:07:45 INFO - 'NO_EM_RESTART': '1', 03:07:45 INFO - 'PAGER': '/bin/cat', 03:07:45 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:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:45 INFO - 'PWD': '/builds/slave/test', 03:07:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:07:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:07:45 INFO - 'SHELL': '/bin/bash', 03:07:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEvQTtmeX9/Listeners', 03:07:45 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 03:07:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:07:45 INFO - 'USER': 'cltbld', 03:07:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:07:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:45 INFO - 'XPC_FLAGS': '0x0', 03:07:45 INFO - 'XPC_SERVICE_NAME': '0', 03:07:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:07:45 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-0274', '--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:45 INFO - mozversion application_buildid: 20160730005025 03:07:45 INFO - mozversion application_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:45 INFO - mozversion application_display_name: Nightly 03:07:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:07:45 INFO - mozversion application_name: Firefox 03:07:45 INFO - mozversion application_remotingname: firefox 03:07:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:07:45 INFO - mozversion application_vendor: Mozilla 03:07:45 INFO - mozversion application_version: 50.0a1 03:07:45 INFO - mozversion platform_buildid: 20160730005025 03:07:45 INFO - mozversion platform_changeset: c3565c8b1cdb575db1c80c7791984a6490598b84 03:07:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:07:45 INFO - mozversion platform_version: 50.0a1 03:07:45 INFO - using testdate: 1469873265 03:07:45 INFO - actual date: 1469873265 03:07:45 INFO - starting webserver on 'localhost:49265' 03:07:45 INFO - SUITE-START | Running 2 tests 03:07:45 INFO - TEST-START | dromaeo_css 03:07:45 INFO - operating with platform_type : mac_ 03:07:45 INFO - Initialising browser for dromaeo_css test... 03:07:45 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpbw9BYJ/profile http://localhost:49265/getInfo.html) 03:07:47 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 03:07:47 INFO - PROCESS | 1664 | colorDepth:24 03:07:47 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 03:07:47 INFO - PROCESS | 1664 | __metrics 03:07:47 INFO - PROCESS | 1664 | 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:47 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:07:48 INFO - TEST-INFO | 1664: exit 0 03:07:48 INFO - Browser initialized. 03:07:48 INFO - Running cycle 1/1 for dromaeo_css test... 03:07:48 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpbw9BYJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:08:00 INFO - PROCESS | 1667 | RSS: Main: 243286016 03:08:00 INFO - PROCESS | 1667 | 03:08:51 INFO - PROCESS | 1667 | 1469873331859 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:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 03:11:05 INFO - PROCESS | 1667 | RSS: Main: 401428480 03:11:05 INFO - PROCESS | 1667 | 03:14:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 03:14:09 INFO - PROCESS | 1667 | RSS: Main: 403820544 03:14:09 INFO - PROCESS | 1667 | 03:17:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 03:17:12 INFO - PROCESS | 1667 | RSS: Main: 379408384 03:17:12 INFO - PROCESS | 1667 | 03:20:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 03:20:15 INFO - PROCESS | 1667 | RSS: Main: 355880960 03:20:15 INFO - PROCESS | 1667 | 03:23:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 03:23:19 INFO - PROCESS | 1667 | RSS: Main: 348962816 03:23:19 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 03:26:22 INFO - PROCESS | 1667 | RSS: Main: 403623936 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | __start_tp_report 03:26:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 03:26:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 03:26:22 INFO - PROCESS | 1667 | |i|pagename|runs| 03:26:22 INFO - PROCESS | 1667 | |0;dojo.html;3410.641882602327;3412.488126780984;3431.330890476357;3433.0387491502356;3484.7734897231676;5630.943690563093;5739.1965124882445;5747.482726554609;5921.644701317919;5932.792352267671;5800.4489573490455;5818.941810581893;5932.762689492426;5938.0499120140785;5969.910451343222;4810.821348769557;5136.64043516954;5172;5255.894882102356;5271.525562699357;5343.919841202385;5362.195670649414;5375.301210842605;5381.623286369956;5410.1073322901875;5155.9688062387295;5181.3782346118705;5198.870028249286;5249.711265880375;5293.04725149473;4489.910201795946;5238.476152384769;5245.160774276097;5248.606354523387;5252.737363131828;5178.40448348441;5181.300524429229;5222.16445368743;5247.580193584542;5256.763445644955;5718.885622287531;5724.9427505724825;5730.570207234432;5732.5987180897355;5735.512481439083;6155.692215389213;6172.783952561639;6218;6243.43809057187;6245.843853903643;6121.785737499211;6220.098085777582;6237.781677641261;6249.187605611287;6283.151774510428;5898.115282707586;5905.202797622352;5911.645301281851;5912.083627037816;5919.792807251725;6181.103739957681;6228.906566401508;6250.406211409909;6282.937170628326;6340.8414789629705;6236.064590311399;6251.74993000284;6260.248770147611;6265.248170219603;6272.843178920563;6149.754009839557;6279.246490421087;6288.056791481223;6293.905591416133;6299.6535190564955;6181.474574661114;6227.283862355796;6257.812265632038;6297.748090076438;6299.96850015747;6267.62394256346;6280.654563999024;6286.371362863677;6291.842703932438;6301.653409062454;4199.790010499462;4881.07259620678;4951.603871690256;4964.751762411865;4988.72562009093;4382.561743825656;4445.755483448376;4463.2635615123145;4490.708103973231;4533.455985281787;5923.348431672599;5943.762249509971;5952.613080149777;5956.374580669054;5959.22530071088;6147.96926015376;6244.968775156095;6248.062790581358;6251.874962500725;6265.906011409833;5748.7125643717645;5790.305163380422;5819.243498345188;5852.326982396994;5861.648301101863;5409.5131438169965;5464.207689884945;5469.15228139639;5477.507024367826;5534.529564987021;5288;5540.445955404508;6288.402601752826;6357.141785859035;6361.650109243943;6154.723037463415;6208.161898143873;6233.875322493434;6247.906281405873;6265.0602409637095;6219.595726277777;6244.968775156095;6251.71867265965;6331.810045698637;6373.107764912823;5414.756335964812;6183.103449999635;6239.0953311768635;6241.21984751906;6259.405356491126;5643.125315576092;5846.9183201107935;6261.467775239155;6288.717007734755;6292.748290068529;5855.531557475307;6011.939880601138;6044.879102417839;6146.274180984569;6185.783497577563;5068.847934561969;6095.347660937622;6129.325774164749;6135.631862088289;6144.078388241799;6002.789902353396;6005.939940600713;6017.759289628541;6028.306744724413;6114.991026480581;6176.907346389898;6200.410960958701;6220.937790622036;6229.750809967551;6258.3741625838875;6068.6358818471035;6072.574919755575;6082.605123608827;6105.542084343603;6140 03:26:22 INFO - PROCESS | 1667 | |1;ext.html;471.7122555241302;502.2942765414594;504.34435234195536;523.2857149990264;542.0324720374132;40429.37209081411;42009.3698594521;42077.368839467395;43460;43496;34423.967280981546;40845.59154408455;42265.57734422655;42693;42696;8837.469751814911;9718.368306060102;9880.703578892644;9898.35660682057;9899.356541824794;8823.029466758651;11960.820587691149;12153;12173.513059477611;12377.876221237808;12127;12178.878211217907;12195.695107622336;12206.694832629211;12206.694832629211;11956.820647690292;12045.518179272818;12054.095942804237;12060.155789094766;12075.577354792584;8648.091950345186;9008.099190080939;11786.233894796811;12088.395580221042;12107.757844843141;40505.59494405048;40861.591384086074;40865;40891;40908.77273681794;3421.469672200813;3485.3203625293077;3495.4407294832704;3791.450239715226;4023.275810354135;31744.682553174403;32137.51793723096;32142.6785732142;32291.677083229337;32377.190570235696;3248.447763880146;3248.5776849009712;3313.353895990283;3402.013416109325;3406.3187362527347;471207;472616;473020;474496;476911;60001.09998350028;60424.697876510334;60529.69735151296;60570;60575;62606;62857;62951.963842893376;62952;63081.053784193275;15296.770548441782;15387.692246155239;15602.141882196362;15626.76559851603;15639.983401079122;10377.792444151075;10630.521626526825;11840.407979601157;12038.638840834788;12061.638150855488;16390;16666.33334666624;16837.41069062577;16969;17023.40418085361;6963.8412092251565;7016.824579385454;7102.538335008208;7120.394766444911;7122.18094919091;897.6454300551246;965.6041023180462;991.2119864707574;1021.6628512590827;1039.3919557059055;23493;29499.41001180007;29741.107766767032;30459.390812183632;30646.08061758151;918.4305730447165;1028.321307936758;1058.6347710040025;1172.806486929664;1180.6162997025829;15197.544073677807;15238.085714857145;15275.541733748005;15510.767338489932;15720.842791572166;553172;645786;648534;649705;654916;60010.69994649999;60043.39956600378;60100.39899600948;60110.39889601048;60169.69915150396;1153.1466714631113;1254.1973137192203;1359.2116572387793;1399.8880089592808;1432.9928350358182;27124.1862744118;29084.854575727833;29811.10566683003;30785.38429231358;31135.882376443216;43779.12441751211;50198.49801502084;50759.73100672365;51093.722656933875;51119;453248;453905;457141;458261;459561;26653;27295;27323.590146147428;28151;28194;48147.5185248143;48304.275435867785;48782;49119.754401227765;49777.751111244215;39239.803800980815;39441.40837887491;40399.7980010098;40416.0622608159;40979;39365.409518857814;39641.60358396379;39696;40589.18821623492;40826.591734083464;38770.0307492315;38952;38970.220595587365;39002.804985974886;39656.60343396529;29280.121596352143;32633.34733305368;34329.65670343264;34859;35591.28817423585;22319.77680223242;25790.647215861816;25853.224403267934;25927.611085834105;26298.474030519665 03:26:22 INFO - PROCESS | 1667 | |2;jquery.html;358.97666651667623;373.6114440981381;375.1184715917593;376.45602104958346;396.2688839091874;15671.764923526142;16503.09232992185;21013.579728405424;21212.68180977283;21345.35963921081;21359.252426165087;21739.891300543513;22280;22301;22632.773672263313;5801.274840644919;5863.970680146594;6042.456178943894;6100.572959892809;6121.908171377433;4195.748255104703;5983.311919129291;6100.755969761204;6103.664298463583;6411.5191360647905;5681.9431805681825;5753.5397168226655;6711.966440167792;6713;6732.124823772902;6417.93582064178;6612.371824676648;6683.365080317362;6685.632290224035;6687;5597.972010139964;5805.941940580625;5817;5818.854528636775;5823.737931793079;26809.865950670122;26929.78815953287;27087.593686094726;27306.317342066606;27317.45365092687;2289.6565515172692;2450.534398464286;2464.29767516257;2465.432950421395;2895.4353900989317;16103.03381797096;16396.426125085563;16731.83268167315;17265.050422226774;17308.653826923517;1853.5551467647801;1916.252661462031;1917.1660327757331;1959.470942845438;1988.99005504973;119242.47953964796;123295;123845.14232286523;125921;126018.3699081508;10647.627333043307;10852.782944341227;11335.829962550568;11342.773144537228;11634.011109055826;14022.088564243291;14025.438982440588;14033.929830350988;14048.297585120703;14076.577702668936;8273.669053237923;8334.374921880762;8396.832063358821;8506.65973361071;8661.52361620117;7412.925870741331;8047.798805029921;8448.915510844812;8475.957620211859;8639;6118.644321935998;6567.802965911031;6790.151231096113;6916.515843890879;6935.35581669438;1691.408007197471;1728.3345911824038;1767.1341042889148;1830.275434132408;1840.7607011088742;888.8046525083034;913.7852604637905;937.3110763588757;1050.2595670052594;1060.8886066963012;22382.664260036112;22925.885370573375;22955.540889182124;22959.311220663407;22988.540229195656;977.6822532676908;994.6220436234329;1028.7222449938474;1035.9015893490107;1041.2763129624989;11190.272632278877;11782.116341274268;12008.63974080779;12032.977196938358;12033.39833008346;542761;568157;601538;607001;614207;117554;118979;119923;120831;122960;923.884514435709;1017.760826205841;1019.3679918450608;1038.0294424712918;1040.9375437473775;14747.55757327282;18787.90606046961;20249.29127480531;20780.896095519427;21862.562748745255;54961.1755823655;56170.43829561652;57429.71285143548;58389.70805145947;58671;78292.60853695695;79327;80382.59808700919;80569;81344.59327703323;5730.312362516524;5833.591648584728;7675.884861726967;8463.36524760633;8683.609237584198;12847.678808029501;13837.515686950908;13853.960952928772;14367.425302987764;14471.348789304297;6995.055667484824;8384.99380074395;9142;10948.56205751761;11827.107662773915;10085.445300508543;11550.249233799776;11724.120690948042;11757.588484403004;12066;11512.30926144434;11512.827307590225;11860.525578976743;12055.939720301692;12063.939680301542;8221.260086592236;8450.788730281793;8452.577371131296;8509.617067232113;8716.520591367536;11609.88390116088;11634.941825291158;11849.466773995018;11955.462004209658;11981.64055078349 03:26:22 INFO - PROCESS | 1667 | |3;mootools.html;1582.3749618900536;1604.2139351717658;1687.8059023212334;1871.2234422714573;1896.6775648139815;8475.32197424206;8824.77938051549;8859.291256699473;8889.377743557954;8951.104889511045;7875.015623047105;7916.010498687664;8445.78885527862;8675.652973881053;8688.565571721405;2317.8609283443006;2335.8481698689616;2345.777151170636;2349.7532759060223;2356.9175078872245;2305.988470057647;2332.7667233276707;2360.398098484884;2362.385779697274;2368.8460250083776;2328.39461739948;2339.590571649963;2342.672025916373;2354.3878591566145;2369.668246445499;2307.526956973833;2321.616933206019;2329.557384097016;2331.603627383349;2335.6730057791924;2312.814974802012;2315.6410756332625;2317.8029867461287;2325.5000174962465;2327.5810354136247;8729.214370706602;8774.341924355635;8894.822103557957;8896.421732587361;8953.686620968236;5162.92255616166;5207.3490813648295;5325.068113080234;5380.085385484477;5395.5953303502;3244.6917542833394;3309.271960168759;3380.5267262583257;3381.628020917697;3387.458006718913;1003.1272792670351;1046.1369370269558;1049.1449468683;1062.8299472084507;1065.7495488560182;11466.942665286704;11506.654800355918;11527.538898444052;11745.412729363497;11751;3784.262068896539;4649.232876575395;4720.881977950578;4738.644601654821;4751.477337492873;9771.071748183911;9814.705558833246;9978.800423991479;9985.700428987142;10014.148797352162;5262.7631756571045;5306.09796334624;5342.065138600729;5357.08929481989;5375.435579264199;4370.147821174841;4478.888027799266;4726.456457507362;4865.197242455026;4897.41231052276;3817.6373244541724;3885.203533275672;3950.545687245946;3971.0270983609203;4012.25773231953;1099.197585762398;1198.221156248428;1221.407617305606;1230.6861750253806;1240.6898275431142;812.3988248695922;860.9620878081901;905.1627244798535;950.334765664038;983.0513554420019;5856.443637854397;5966.880662386728;6015;6149.84625384369;6209.410106039919;420.9917248190566;439.51872699394113;443.1425192253001;443.5475814669084;451.6973627669469;3986.421968814506;4025.5546474774396;4105.548389677133;4113.8971525712095;4125.051238215227;10278.434686092336;10508.474576271308;10573.471326433648;10621.2034097443;10705.83941240882;10607.310524815863;10608.098311643442;10672.199585031154;10693.358398495959;10708.357498550113;6997;7940.444168908237;7982.082060562993;8293.087760346236;8313;5860.355360910211;5944.970275148597;5956.404359564009;5970.0746384310505;5998.070299103646;10045.748856278651;10058.446785426871;10059.748506287402;10159.644412445528;10165.84751228747;10077.344972576759;10108.54511546997;10259.333143345659;10318.43248621303;10418.062374386342;6996.720131194898;7191.137063552407;7277.16312624055;7538.924610753822;7564.394848412004;8130.715424960098;8364.623591938256;8396.832063358577;8446.239838414573;8475.872861906953;7257.165425976081;7341.192468828318;7398.371138453292;7489.850202995801;7554.433417493819;7204.135503739585;7323.450741194325;7353.338199562065;7354.448416368687;7359.153697324876;6947.166340039228;6964.79105626832;6974.895376569254;7013.824654383681;7026.648667566704;4707.8587642370785;4826.034793041335;4838.685485443435;4862.6839255448285;4916.188828843201;4802.423709154924;5094.2103973884105;5126.4360920297995;5183.637145399936;5195.3246078009615 03:26:22 INFO - PROCESS | 1667 | |4;prototype.html;303.49165148376477;308.1787037544942;320.53362357769447;323.0518428412611;323.501807216886;4295.818649871287;5146.305248791412;5167.328247327847;5631.043702056412;5634.690092044941;5187.818426355078;5255.500727430888;5298.36419629644;5407.783688652459;5478.506934375905;3432.5537680101615;4137.055583875589;4201.642860356873;4205.915881682365;4226.852060177895;3455.9654403455893;4160.750354978711;4184.225918205127;4192.811323490435;4234.21666991606;3451.4650229214512;3487.4943133245665;3867.8066096695193;3956.4312813609104;4200.991761977134;4115.300398932189;4128.256913755523;4129.359949207877;4137.8551750688885;4153.501579810412;3013.8697988254403;3594.9377203759586;3636.5999740028574;3639.9636003640153;3646.872359467406;4690.296455531698;5063.316452278932;5378.058839703036;5380.838574842761;5413.972930135364;4441.489228738704;4792.424909010941;4799.2801079837955;4803.78382972767;4848.369711937426;5625.240592519998;5720.22776925114;5744.913826292609;5767.307923049219;5849.912251316234;3561.4301711726;3580.910477238064;3834.578196398394;3863.16941857502;3881.4371916072296;4752.217918280645;5017.713397724497;5485.259489968843;6005.699715014275;6100.542459315569;4075.409065685459;4095.447114639545;4096.856610018635;4108.055147316134;4123.958760412417;4172.70791044624;4178.331466965271;4193.475815523059;4198.250146154274;4202.768847713405;3755.3615885299564;4193.39195816611;4799.472058073609;4812.759362031885;4872.634552408584;4830.782614782343;5425.945740542544;5506.174073888948;5821.126830975388;5887.470127688529;1105.8838821923744;1353.661584603849;1421.822272215973;1440.6326386771307;1446.305773228835;881.96809732613;890.9955450222708;921.0099143420849;925.2089463508738;942.4816351007056;4410.977945110254;4462.107578484316;5332.33345831771;5342.519173274424;5362.946370536322;4955.5044495550155;5366.114591092426;5834.970825145932;5847.3275573308765;5859.912101318484;4304.418903447994;4645.864041771858;4874.926876096862;5312.840614781563;5397.676139431646;2710.6069619905;2933.313344826733;3037.346970401374;3104.7826652134586;3106.487429574095;5511.071125106181;6043.516518678495;6054.305833903871;6060.515158787286;6064.36324185963;5760.94239057604;5996.280446346466;6122.173506576644;6198.566100373021;6242.313345531988;5187.507186817246;5787.421257874179;6241.906371404432;6244.128640242988;6248;4977.950220497749;5032.094223039888;5739.139129130664;6012.488938440281;6093.634381937098;5080.7205603692155;5883.941160588511;5912.201852749996;6005.339412664516;6019.819405417845;5501.587380946525;5593.720313984366;6048.183495228264;6311.873762524816;6349.269813980033;3767.9261410497875;3986.700997425263;4002.239574480839;4032.475778148822;4082.1427500225286;5586.357568879631;5970.522358211246;6033;6080.422359875804;6088.934436473688;4612.838550650711;5082.186850104114;5095.898082038412;5467.808626698205;5830.125481177857;4905.90188196227;5185.714785686823;5399.811006614749;5771.971140144273;5890.528757699288;4765.046331004271;5435.782568697366;5689.1750696148;5791.739371728367;5817.970910145422;5582.720863956704;5730.598858079894;5802.564807639528;5870.236869206976;5882.382349853375;5173.870653233699;5177.948220517746;5281.125200553748;5690.788816276009;5748.310202775694 03:26:22 INFO - PROCESS | 1667 | |5;yui.html;747.2378174262253;752.7666423408742;783.7844592736997;854.8504011797936;867.7526904832124;15384.769228461568;15997.680046399066;16002.759958600602;16061.598460038505;16063.759043614325;14617.634559136028;14798.112113273211;14816.036957597775;14858.33137508812;15033.098014119161;687.226869771507;707.6850801393382;719.4783781758209;849.2611428057583;885.0618344554761;874.6020560644891;877.7937184761576;878.2798105553449;878.4290211362602;878.5343767803042;913.2967614936495;927.211869910574;928.5450129436595;929.7629104578323;930.2604429478581;923.4090182283344;924.3806649544821;924.4037595750704;924.4037595750704;925.8240934222476;534.5349545895097;596.8149873539173;669.6517810738437;687.6767919078026;688.1157712339619;14453.566393008226;14493.637659058502;14521.78217326741;14526.636834079232;14540.854591454161;13298.268595227259;13844.723105537832;13852.238126903016;13861.850943650636;14049;11206.607768728056;11766.705832354259;11823.408829558573;11851.88148118525;12315.93842030784;4463.419755431773;4519.096180763828;4643.257078867365;4661.137689527414;4717.858464246078;105730.41404379018;106731;107566.92433075648;107567;111442;9318.86021709675;10734.838977415346;10818.350898945931;10889.455527223765;11165.720856978478;21172.153113875273;21367.67948480774;21377.57244855094;21404.892975535022;21504.892475537836;7742.496737712031;7781.144074151839;7793.844123117506;7794.571298578519;7955.4431189817315;4291.249031419489;4340.110277393127;4350.934735978963;4357.172137293904;4514.25514790056;3316.403047451482;3429.8628054877527;3431.4166591679473;3457.775244609092;3460.7058400035776;642.4667525953447;654.8395643067383;658.8846951783419;669.3908543225641;671.8320419895026;594.8334466349429;596.4786915182904;600.1897438458046;634.511426201822;653.2161406312349;8763.868541971875;8884.094234928009;8914.777130571658;8915.955420222857;8917.955410222907;598.4703537369372;612.3050337866554;623.6788054152115;628.5694299404922;645.5287640022809;4267.274563324242;4283.5502272260965;4305.16049370376;4342.609165175149;4383.802728877208;98100;149225;149426;151858;154367;108226;133285.3335733315;134888;136349;138846;117087;118253;120127;120175.39912300557;122828;8375.664973400995;8396.286315663237;8459.450135741157;8497.532635705096;8757;64416.03375949458;64418;64980;66094.33905661074;66483.66758166178;96912.0308796903;99598;100376.49811750895;100448.49775751075;100457;11561.421928903354;11609.245399049034;11657.766844663229;12016.63950081499;12093.57672481459;12103.213291136048;12106.092043096627;12138.089643276966;12248.632541023784;12589.496420143092;11749.236299640495;11759.88240117622;11773.941130294294;11823.52705891789;11852.466639001093;11544.422778861192;11605;11703.180777345504;11711.648650540497;12391.62825115248;11610.767784644091;11650.825237621273;11708.063354931415;12144.028477721939;12188.634340969786;9617.519124043909;9978.800423991624;10134.847977280495;10158.339707918962;10200.897991020292;8662.393632445668;9549.245108192152;9908.554115064695;10064.949675251577;10134.189264858942 03:26:22 INFO - PROCESS | 1667 | __end_tp_report 03:26:22 INFO - PROCESS | 1667 | __start_cc_report 03:26:22 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1940 03:26:22 INFO - PROCESS | 1667 | __end_cc_report 03:26:22 INFO - PROCESS | 1667 | __startTimestamp1469874382728__endTimestamp 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | ------- Summary: start ------- 03:26:22 INFO - PROCESS | 1667 | Number of tests: 6 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5762.90 Median:6002.79 stddev:646.96 (10.8%) stddev-sans-first:622.08 03:26:22 INFO - PROCESS | 1667 | Values: 3410.6 3412.5 3431.3 3433.0 3484.8 5630.9 5739.2 5747.5 5921.6 5932.8 5800.4 5818.9 5932.8 5938.0 5969.9 4810.8 5136.6 5172.0 5255.9 5271.5 5343.9 5362.2 5375.3 5381.6 5410.1 5156.0 5181.4 5198.9 5249.7 5293.0 4489.9 5238.5 5245.2 5248.6 5252.7 5178.4 5181.3 5222.2 5247.6 5256.8 5718.9 5724.9 5730.6 5732.6 5735.5 6155.7 6172.8 6218.0 6243.4 6245.8 6121.8 6220.1 6237.8 6249.2 6283.2 5898.1 5905.2 5911.6 5912.1 5919.8 6181.1 6228.9 6250.4 6282.9 6340.8 6236.1 6251.7 6260.2 6265.2 6272.8 6149.8 6279.2 6288.1 6293.9 6299.7 6181.5 6227.3 6257.8 6297.7 6300.0 6267.6 6280.7 6286.4 6291.8 6301.7 4199.8 4881.1 4951.6 4964.8 4988.7 4382.6 4445.8 4463.3 4490.7 4533.5 5923.3 5943.8 5952.6 5956.4 5959.2 6148.0 6245.0 6248.1 6251.9 6265.9 5748.7 5790.3 5819.2 5852.3 5861.6 5409.5 5464.2 5469.2 5477.5 5534.5 5288.0 5540.4 6288.4 6357.1 6361.7 6154.7 6208.2 6233.9 6247.9 6265.1 6219.6 6245.0 6251.7 6331.8 6373.1 5414.8 6183.1 6239.1 6241.2 6259.4 5643.1 5846.9 6261.5 6288.7 6292.7 5855.5 6011.9 6044.9 6146.3 6185.8 5068.8 6095.3 6129.3 6135.6 6144.1 6002.8 6005.9 6017.8 6028.3 6115.0 6176.9 6200.4 6220.9 6229.8 6258.4 6068.6 6072.6 6082.6 6105.5 6140.0 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:66518.17 Median:38986.51 stddev:140339.09 (360.0%) stddev-sans-first:140645.17 03:26:22 INFO - PROCESS | 1667 | Values: 471.7 502.3 504.3 523.3 542.0 40429.4 42009.4 42077.4 43460.0 43496.0 34424.0 40845.6 42265.6 42693.0 42696.0 8837.5 9718.4 9880.7 9898.4 9899.4 8823.0 11960.8 12153.0 12173.5 12377.9 12127.0 12178.9 12195.7 12206.7 12206.7 11956.8 12045.5 12054.1 12060.2 12075.6 8648.1 9008.1 11786.2 12088.4 12107.8 40505.6 40861.6 40865.0 40891.0 40908.8 3421.5 3485.3 3495.4 3791.5 4023.3 31744.7 32137.5 32142.7 32291.7 32377.2 3248.4 3248.6 3313.4 3402.0 3406.3 471207.0 472616.0 473020.0 474496.0 476911.0 60001.1 60424.7 60529.7 60570.0 60575.0 62606.0 62857.0 62952.0 62952.0 63081.1 15296.8 15387.7 15602.1 15626.8 15640.0 10377.8 10630.5 11840.4 12038.6 12061.6 16390.0 16666.3 16837.4 16969.0 17023.4 6963.8 7016.8 7102.5 7120.4 7122.2 897.6 965.6 991.2 1021.7 1039.4 23493.0 29499.4 29741.1 30459.4 30646.1 918.4 1028.3 1058.6 1172.8 1180.6 15197.5 15238.1 15275.5 15510.8 15720.8 553172.0 645786.0 648534.0 649705.0 654916.0 60010.7 60043.4 60100.4 60110.4 60169.7 1153.1 1254.2 1359.2 1399.9 1433.0 27124.2 29084.9 29811.1 30785.4 31135.9 43779.1 50198.5 50759.7 51093.7 51119.0 453248.0 453905.0 457141.0 458261.0 459561.0 26653.0 27295.0 27323.6 28151.0 28194.0 48147.5 48304.3 48782.0 49119.8 49777.8 39239.8 39441.4 40399.8 40416.1 40979.0 39365.4 39641.6 39696.0 40589.2 40826.6 38770.0 38952.0 38970.2 39002.8 39656.6 29280.1 32633.3 34329.7 34859.0 35591.3 22319.8 25790.6 25853.2 25927.6 26298.5 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35256.27 Median:22973.93 stddev:98034.06 (426.7%) stddev-sans-first:98274.05 03:26:22 INFO - PROCESS | 1667 | Values: 359.0 373.6 375.1 376.5 396.3 15671.8 16503.1 21013.6 21212.7 21345.4 21359.3 21739.9 22280.0 22301.0 22632.8 5801.3 5864.0 6042.5 6100.6 6121.9 4195.7 5983.3 6100.8 6103.7 6411.5 5681.9 5753.5 6712.0 6713.0 6732.1 6417.9 6612.4 6683.4 6685.6 6687.0 5598.0 5805.9 5817.0 5818.9 5823.7 26809.9 26929.8 27087.6 27306.3 27317.5 2289.7 2450.5 2464.3 2465.4 2895.4 16103.0 16396.4 16731.8 17265.1 17308.7 1853.6 1916.3 1917.2 1959.5 1989.0 119242.5 123295.0 123845.1 125921.0 126018.4 10647.6 10852.8 11335.8 11342.8 11634.0 14022.1 14025.4 14033.9 14048.3 14076.6 8273.7 8334.4 8396.8 8506.7 8661.5 7412.9 8047.8 8448.9 8476.0 8639.0 6118.6 6567.8 6790.2 6916.5 6935.4 1691.4 1728.3 1767.1 1830.3 1840.8 888.8 913.8 937.3 1050.3 1060.9 22382.7 22925.9 22955.5 22959.3 22988.5 977.7 994.6 1028.7 1035.9 1041.3 11190.3 11782.1 12008.6 12033.0 12033.4 542761.0 568157.0 601538.0 607001.0 614207.0 117554.0 118979.0 119923.0 120831.0 122960.0 923.9 1017.8 1019.4 1038.0 1040.9 14747.6 18787.9 20249.3 20780.9 21862.6 54961.2 56170.4 57429.7 58389.7 58671.0 78292.6 79327.0 80382.6 80569.0 81344.6 5730.3 5833.6 7675.9 8463.4 8683.6 12847.7 13837.5 13854.0 14367.4 14471.3 6995.1 8385.0 9142.0 10948.6 11827.1 10085.4 11550.2 11724.1 11757.6 12066.0 11512.3 11512.8 11860.5 12055.9 12063.9 8221.3 8450.8 8452.6 8509.6 8716.5 11609.9 11634.9 11849.5 11955.5 11981.6 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5676.51 Median:4714.37 stddev:3213.91 (68.2%) stddev-sans-first:3208.20 03:26:22 INFO - PROCESS | 1667 | Values: 1582.4 1604.2 1687.8 1871.2 1896.7 8475.3 8824.8 8859.3 8889.4 8951.1 7875.0 7916.0 8445.8 8675.7 8688.6 2317.9 2335.8 2345.8 2349.8 2356.9 2306.0 2332.8 2360.4 2362.4 2368.8 2328.4 2339.6 2342.7 2354.4 2369.7 2307.5 2321.6 2329.6 2331.6 2335.7 2312.8 2315.6 2317.8 2325.5 2327.6 8729.2 8774.3 8894.8 8896.4 8953.7 5162.9 5207.3 5325.1 5380.1 5395.6 3244.7 3309.3 3380.5 3381.6 3387.5 1003.1 1046.1 1049.1 1062.8 1065.7 11466.9 11506.7 11527.5 11745.4 11751.0 3784.3 4649.2 4720.9 4738.6 4751.5 9771.1 9814.7 9978.8 9985.7 10014.1 5262.8 5306.1 5342.1 5357.1 5375.4 4370.1 4478.9 4726.5 4865.2 4897.4 3817.6 3885.2 3950.5 3971.0 4012.3 1099.2 1198.2 1221.4 1230.7 1240.7 812.4 861.0 905.2 950.3 983.1 5856.4 5966.9 6015.0 6149.8 6209.4 421.0 439.5 443.1 443.5 451.7 3986.4 4025.6 4105.5 4113.9 4125.1 10278.4 10508.5 10573.5 10621.2 10705.8 10607.3 10608.1 10672.2 10693.4 10708.4 6997.0 7940.4 7982.1 8293.1 8313.0 5860.4 5945.0 5956.4 5970.1 5998.1 10045.7 10058.4 10059.7 10159.6 10165.8 10077.3 10108.5 10259.3 10318.4 10418.1 6996.7 7191.1 7277.2 7538.9 7564.4 8130.7 8364.6 8396.8 8446.2 8475.9 7257.2 7341.2 7398.4 7489.9 7554.4 7204.1 7323.5 7353.3 7354.4 7359.2 6947.2 6964.8 6974.9 7013.8 7026.6 4707.9 4826.0 4838.7 4862.7 4916.2 4802.4 5094.2 5126.4 5183.6 5195.3 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4631.11 Median:5156.82 stddev:1432.37 (27.8%) stddev-sans-first:1399.07 03:26:22 INFO - PROCESS | 1667 | Values: 303.5 308.2 320.5 323.1 323.5 4295.8 5146.3 5167.3 5631.0 5634.7 5187.8 5255.5 5298.4 5407.8 5478.5 3432.6 4137.1 4201.6 4205.9 4226.9 3456.0 4160.8 4184.2 4192.8 4234.2 3451.5 3487.5 3867.8 3956.4 4201.0 4115.3 4128.3 4129.4 4137.9 4153.5 3013.9 3594.9 3636.6 3640.0 3646.9 4690.3 5063.3 5378.1 5380.8 5414.0 4441.5 4792.4 4799.3 4803.8 4848.4 5625.2 5720.2 5744.9 5767.3 5849.9 3561.4 3580.9 3834.6 3863.2 3881.4 4752.2 5017.7 5485.3 6005.7 6100.5 4075.4 4095.4 4096.9 4108.1 4124.0 4172.7 4178.3 4193.5 4198.3 4202.8 3755.4 4193.4 4799.5 4812.8 4872.6 4830.8 5425.9 5506.2 5821.1 5887.5 1105.9 1353.7 1421.8 1440.6 1446.3 882.0 891.0 921.0 925.2 942.5 4411.0 4462.1 5332.3 5342.5 5362.9 4955.5 5366.1 5835.0 5847.3 5859.9 4304.4 4645.9 4874.9 5312.8 5397.7 2710.6 2933.3 3037.3 3104.8 3106.5 5511.1 6043.5 6054.3 6060.5 6064.4 5760.9 5996.3 6122.2 6198.6 6242.3 5187.5 5787.4 6241.9 6244.1 6248.0 4978.0 5032.1 5739.1 6012.5 6093.6 5080.7 5883.9 5912.2 6005.3 6019.8 5501.6 5593.7 6048.2 6311.9 6349.3 3767.9 3986.7 4002.2 4032.5 4082.1 5586.4 5970.5 6033.0 6080.4 6088.9 4612.8 5082.2 5095.9 5467.8 5830.1 4905.9 5185.7 5399.8 5772.0 5890.5 4765.0 5435.8 5689.2 5791.7 5818.0 5582.7 5730.6 5802.6 5870.2 5882.4 5173.9 5177.9 5281.1 5690.8 5748.3 03:26:22 INFO - PROCESS | 1667 | 03:26:22 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:24830.29 Median:3863.99 stddev:40263.95 (1042.0%) stddev-sans-first:40336.29 03:26:22 INFO - PROCESS | 1667 | Values: 747.2 752.8 783.8 854.9 867.8 15384.8 15997.7 16002.8 16061.6 16063.8 14617.6 14798.1 14816.0 14858.3 15033.1 687.2 707.7 719.5 849.3 885.1 874.6 877.8 878.3 878.4 878.5 913.3 927.2 928.5 929.8 930.3 923.4 924.4 924.4 924.4 925.8 534.5 596.8 669.7 687.7 688.1 14453.6 14493.6 14521.8 14526.6 14540.9 13298.3 13844.7 13852.2 13861.9 14049.0 11206.6 11766.7 11823.4 11851.9 12315.9 4463.4 4519.1 4643.3 4661.1 4717.9 105730.4 106731.0 107566.9 107567.0 111442.0 9318.9 10734.8 10818.4 10889.5 11165.7 21172.2 21367.7 21377.6 21404.9 21504.9 7742.5 7781.1 7793.8 7794.6 7955.4 4291.2 4340.1 4350.9 4357.2 4514.3 3316.4 3429.9 3431.4 3457.8 3460.7 642.5 654.8 658.9 669.4 671.8 594.8 596.5 600.2 634.5 653.2 8763.9 8884.1 8914.8 8916.0 8918.0 598.5 612.3 623.7 628.6 645.5 4267.3 4283.6 4305.2 4342.6 4383.8 98100.0 149225.0 149426.0 151858.0 154367.0 108226.0 133285.3 134888.0 136349.0 138846.0 117087.0 118253.0 120127.0 120175.4 122828.0 8375.7 8396.3 8459.5 8497.5 8757.0 64416.0 64418.0 64980.0 66094.3 66483.7 96912.0 99598.0 100376.5 100448.5 100457.0 11561.4 11609.2 11657.8 12016.6 12093.6 12103.2 12106.1 12138.1 12248.6 12589.5 11749.2 11759.9 11773.9 11823.5 11852.5 11544.4 11605.0 11703.2 11711.6 12391.6 11610.8 11650.8 11708.1 12144.0 12188.6 9617.5 9978.8 10134.8 10158.3 10200.9 8662.4 9549.2 9908.6 10064.9 10134.2 03:26:22 INFO - PROCESS | 1667 | -------- Summary: end -------- 03:26:22 INFO - PROCESS | 1667 | 03:26:23 INFO - TEST-INFO | 1667: exit 0 03:26:23 INFO - TEST-OK | dromaeo_css | took 1117892ms 03:26:23 INFO - TEST-START | kraken 03:26:23 INFO - operating with platform_type : mac_ 03:26:23 INFO - Initialising browser for kraken test... 03:26:23 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmp4VtFlN/profile http://localhost:49265/getInfo.html) 03:26:25 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 03:26:25 INFO - PROCESS | 1675 | colorDepth:24 03:26:25 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 03:26:25 INFO - PROCESS | 1675 | __metrics 03:26:25 INFO - PROCESS | 1675 | 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:25 INFO - PROCESS | 1675 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:26:26 INFO - TEST-INFO | 1675: exit 0 03:26:26 INFO - Browser initialized. 03:26:26 INFO - Running cycle 1/1 for kraken test... 03:26:26 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmp4VtFlN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:38 INFO - PROCESS | 1676 | RSS: Main: 243306496 03:26:38 INFO - PROCESS | 1676 | 03:26:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 03:26:41 INFO - PROCESS | 1676 | RSS: Main: 323928064 03:26:41 INFO - PROCESS | 1676 | 03:26:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 03:26:45 INFO - PROCESS | 1676 | RSS: Main: 330944512 03:26:45 INFO - PROCESS | 1676 | 03:26:48 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 03:26:49 INFO - PROCESS | 1676 | RSS: Main: 427556864 03:26:49 INFO - PROCESS | 1676 | 03:26:51 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 03:26:51 INFO - PROCESS | 1676 | RSS: Main: 330530816 03:26:51 INFO - PROCESS | 1676 | 03:26:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:26:54 INFO - PROCESS | 1676 | RSS: Main: 334864384 03:26:54 INFO - PROCESS | 1676 | 03:26:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 03:26:58 INFO - PROCESS | 1676 | RSS: Main: 371556352 03:26:58 INFO - PROCESS | 1676 | 03:27:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 03:27:01 INFO - PROCESS | 1676 | RSS: Main: 373841920 03:27:01 INFO - PROCESS | 1676 | 03:27:04 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 03:27:05 INFO - PROCESS | 1676 | RSS: Main: 373006336 03:27:05 INFO - PROCESS | 1676 | 03:27:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:27:07 INFO - PROCESS | 1676 | RSS: Main: 344477696 03:27:07 INFO - PROCESS | 1676 | 03:27:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:27:09 INFO - PROCESS | 1676 | RSS: Main: 349966336 03:27:09 INFO - PROCESS | 1676 | 03:27:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:27:12 INFO - PROCESS | 1676 | RSS: Main: 359411712 03:27:12 INFO - PROCESS | 1676 | 03:27:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:27:16 INFO - PROCESS | 1676 | RSS: Main: 359624704 03:27:16 INFO - PROCESS | 1676 | 03:27:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:27:19 INFO - PROCESS | 1676 | RSS: Main: 360742912 03:27:19 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 03:27:21 INFO - PROCESS | 1676 | RSS: Main: 404156416 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | __start_tp_report 03:27:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:27:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:27:21 INFO - PROCESS | 1676 | |i|pagename|runs| 03:27:21 INFO - PROCESS | 1676 | |0;ai-astar;127;168;162;113;113;112;113;113;111;110 03:27:21 INFO - PROCESS | 1676 | |1;audio-beat-detection;141;108;106;163;104;107;129;106;138;108 03:27:21 INFO - PROCESS | 1676 | |2;audio-dft;182;182;184;187;183;182;183;181;184;184 03:27:21 INFO - PROCESS | 1676 | |3;audio-fft;78;58;57;104;57;58;61;65;83;73 03:27:21 INFO - PROCESS | 1676 | |4;audio-oscillator;84;68;90;69;72;68;96;88;93;118 03:27:21 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;117;121;90;88;90;87;90;86;90;86 03:27:21 INFO - PROCESS | 1676 | |6;imaging-darkroom;95;94;94;95;95;94;94;96;95;94 03:27:21 INFO - PROCESS | 1676 | |7;imaging-desaturate;82;89;86;86;88;127;87;124;129;92 03:27:21 INFO - PROCESS | 1676 | |8;json-parse-financial;54;52;51;52;53;55;52;52;54;52 03:27:21 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;67;74;73;74;76;72;71;71;70;71 03:27:21 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;85;83;86;86;85;83;84;85;136 03:27:21 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;140;127;137;123;154;146;151;147;142;137 03:27:21 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;168;167;205;226;192;166;165;159;166;168 03:27:21 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;67;68;66;66;67;67;66;66;66 03:27:21 INFO - PROCESS | 1676 | __end_tp_report 03:27:21 INFO - PROCESS | 1676 | __start_cc_report 03:27:21 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2099 03:27:21 INFO - PROCESS | 1676 | __end_cc_report 03:27:21 INFO - PROCESS | 1676 | __startTimestamp1469874441823__endTimestamp 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:27:21 INFO - PROCESS | 1676 | Number of tests: 14 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:124.20 Median:113.00 stddev:22.06 (19.5%) stddev-sans-first:23.38 03:27:21 INFO - PROCESS | 1676 | Values: 127.0 168.0 162.0 113.0 113.0 112.0 113.0 113.0 111.0 110.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:118.50 stddev:20.52 (17.3%) stddev-sans-first:20.45 03:27:21 INFO - PROCESS | 1676 | Values: 141.0 108.0 106.0 163.0 104.0 107.0 129.0 106.0 138.0 108.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.69 (0.9%) stddev-sans-first:1.73 03:27:21 INFO - PROCESS | 1676 | Values: 182.0 182.0 184.0 187.0 183.0 182.0 183.0 181.0 184.0 184.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:69.40 Median:63.00 stddev:15.37 (24.4%) stddev-sans-first:15.99 03:27:21 INFO - PROCESS | 1676 | Values: 78.0 58.0 57.0 104.0 57.0 58.0 61.0 65.0 83.0 73.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:86.00 stddev:16.02 (18.6%) stddev-sans-first:16.99 03:27:21 INFO - PROCESS | 1676 | Values: 84.0 68.0 90.0 69.0 72.0 68.0 96.0 88.0 93.0 118.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:89.00 stddev:13.05 (14.7%) stddev-sans-first:11.01 03:27:21 INFO - PROCESS | 1676 | Values: 117.0 121.0 90.0 88.0 90.0 87.0 90.0 86.0 90.0 86.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 03:27:21 INFO - PROCESS | 1676 | Values: 95.0 94.0 94.0 95.0 95.0 94.0 94.0 96.0 95.0 94.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:86.50 stddev:19.29 (22.3%) stddev-sans-first:19.46 03:27:21 INFO - PROCESS | 1676 | Values: 82.0 89.0 86.0 86.0 88.0 127.0 87.0 124.0 129.0 92.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.25 (2.4%) stddev-sans-first:1.24 03:27:21 INFO - PROCESS | 1676 | Values: 54.0 52.0 51.0 52.0 53.0 55.0 52.0 52.0 54.0 52.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:2.51 (3.5%) stddev-sans-first:1.94 03:27:21 INFO - PROCESS | 1676 | Values: 67.0 74.0 73.0 74.0 76.0 72.0 71.0 71.0 70.0 71.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.00 stddev:16.27 (19.1%) stddev-sans-first:17.16 03:27:21 INFO - PROCESS | 1676 | Values: 85.0 85.0 83.0 86.0 86.0 85.0 83.0 84.0 85.0 136.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:144.00 stddev:9.89 (6.9%) stddev-sans-first:10.49 03:27:21 INFO - PROCESS | 1676 | Values: 140.0 127.0 137.0 123.0 154.0 146.0 151.0 147.0 142.0 137.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:168.00 stddev:22.03 (13.1%) stddev-sans-first:23.05 03:27:21 INFO - PROCESS | 1676 | Values: 168.0 167.0 205.0 226.0 192.0 166.0 165.0 159.0 166.0 168.0 03:27:21 INFO - PROCESS | 1676 | 03:27:21 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 03:27:21 INFO - PROCESS | 1676 | Values: 67.0 67.0 68.0 66.0 66.0 67.0 67.0 66.0 66.0 66.0 03:27:21 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:27:21 INFO - PROCESS | 1676 | 03:27:22 INFO - TEST-INFO | 1676: exit 0 03:27:22 INFO - TEST-OK | kraken | took 59262ms 03:27:22 INFO - SUITE-END | took 1177s 03:27:22 INFO - Completed test suite (00:19:37) 03:27:22 INFO - javascript benchmark 03:27:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3410.641882602327, 3412.488126780984, 3431.330890476357, 3433.0387491502356, 3484.7734897231676, 5630.943690563093, 5739.1965124882445, 5747.482726554609, 5921.644701317919, 5932.792352267671, 5800.4489573490455, 5818.941810581893, 5932.762689492426, 5938.0499120140785, 5969.910451343222, 4810.821348769557, 5136.64043516954, 5172.0, 5255.894882102356, 5271.525562699357, 5343.919841202385, 5362.195670649414, 5375.301210842605, 5381.623286369956, 5410.1073322901875, 5155.9688062387295, 5181.3782346118705, 5198.870028249286, 5249.711265880375, 5293.04725149473, 4489.910201795946, 5238.476152384769, 5245.160774276097, 5248.606354523387, 5252.737363131828, 5178.40448348441, 5181.300524429229, 5222.16445368743, 5247.580193584542, 5256.763445644955, 5718.885622287531, 5724.9427505724825, 5730.570207234432, 5732.5987180897355, 5735.512481439083, 6155.692215389213, 6172.783952561639, 6218.0, 6243.43809057187, 6245.843853903643, 6121.785737499211, 6220.098085777582, 6237.781677641261, 6249.187605611287, 6283.151774510428, 5898.115282707586, 5905.202797622352, 5911.645301281851, 5912.083627037816, 5919.792807251725, 6181.103739957681, 6228.906566401508, 6250.406211409909, 6282.937170628326, 6340.8414789629705, 6236.064590311399, 6251.74993000284, 6260.248770147611, 6265.248170219603, 6272.843178920563, 6149.754009839557, 6279.246490421087, 6288.056791481223, 6293.905591416133, 6299.6535190564955, 6181.474574661114, 6227.283862355796, 6257.812265632038, 6297.748090076438, 6299.96850015747, 6267.62394256346, 6280.654563999024, 6286.371362863677, 6291.842703932438, 6301.653409062454, 4199.790010499462, 4881.07259620678, 4951.603871690256, 4964.751762411865, 4988.72562009093, 4382.561743825656, 4445.755483448376, 4463.2635615123145, 4490.708103973231, 4533.455985281787, 5923.348431672599, 5943.762249509971, 5952.613080149777, 5956.374580669054, 5959.22530071088, 6147.96926015376, 6244.968775156095, 6248.062790581358, 6251.874962500725, 6265.906011409833, 5748.7125643717645, 5790.305163380422, 5819.243498345188, 5852.326982396994, 5861.648301101863, 5409.5131438169965, 5464.207689884945, 5469.15228139639, 5477.507024367826, 5534.529564987021, 5288.0, 5540.445955404508, 6288.402601752826, 6357.141785859035, 6361.650109243943, 6154.723037463415, 6208.161898143873, 6233.875322493434, 6247.906281405873, 6265.0602409637095, 6219.595726277777, 6244.968775156095, 6251.71867265965, 6331.810045698637, 6373.107764912823, 5414.756335964812, 6183.103449999635, 6239.0953311768635, 6241.21984751906, 6259.405356491126, 5643.125315576092, 5846.9183201107935, 6261.467775239155, 6288.717007734755, 6292.748290068529, 5855.531557475307, 6011.939880601138, 6044.879102417839, 6146.274180984569, 6185.783497577563, 5068.847934561969, 6095.347660937622, 6129.325774164749, 6135.631862088289, 6144.078388241799, 6002.789902353396, 6005.939940600713, 6017.759289628541, 6028.306744724413, 6114.991026480581, 6176.907346389898, 6200.410960958701, 6220.937790622036, 6229.750809967551, 6258.3741625838875, 6068.6358818471035, 6072.574919755575, 6082.605123608827, 6105.542084343603, 6140.0], "value": 5722.568685741026, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.7122555241302, 502.2942765414594, 504.34435234195536, 523.2857149990264, 542.0324720374132, 40429.37209081411, 42009.3698594521, 42077.368839467395, 43460.0, 43496.0, 34423.967280981546, 40845.59154408455, 42265.57734422655, 42693.0, 42696.0, 8837.469751814911, 9718.368306060102, 9880.703578892644, 9898.35660682057, 9899.356541824794, 8823.029466758651, 11960.820587691149, 12153.0, 12173.513059477611, 12377.876221237808, 12127.0, 12178.878211217907, 12195.695107622336, 12206.694832629211, 12206.694832629211, 11956.820647690292, 12045.518179272818, 12054.095942804237, 12060.155789094766, 12075.577354792584, 8648.091950345186, 9008.099190080939, 11786.233894796811, 12088.395580221042, 12107.757844843141, 40505.59494405048, 40861.591384086074, 40865.0, 40891.0, 40908.77273681794, 3421.469672200813, 3485.3203625293077, 3495.4407294832704, 3791.450239715226, 4023.275810354135, 31744.682553174403, 32137.51793723096, 32142.6785732142, 32291.677083229337, 32377.190570235696, 3248.447763880146, 3248.5776849009712, 3313.353895990283, 3402.013416109325, 3406.3187362527347, 471207.0, 472616.0, 473020.0, 474496.0, 476911.0, 60001.09998350028, 60424.697876510334, 60529.69735151296, 60570.0, 60575.0, 62606.0, 62857.0, 62951.963842893376, 62952.0, 63081.053784193275, 15296.770548441782, 15387.692246155239, 15602.141882196362, 15626.76559851603, 15639.983401079122, 10377.792444151075, 10630.521626526825, 11840.407979601157, 12038.638840834788, 12061.638150855488, 16390.0, 16666.33334666624, 16837.41069062577, 16969.0, 17023.40418085361, 6963.8412092251565, 7016.824579385454, 7102.538335008208, 7120.394766444911, 7122.18094919091, 897.6454300551246, 965.6041023180462, 991.2119864707574, 1021.6628512590827, 1039.3919557059055, 23493.0, 29499.41001180007, 29741.107766767032, 30459.390812183632, 30646.08061758151, 918.4305730447165, 1028.321307936758, 1058.6347710040025, 1172.806486929664, 1180.6162997025829, 15197.544073677807, 15238.085714857145, 15275.541733748005, 15510.767338489932, 15720.842791572166, 553172.0, 645786.0, 648534.0, 649705.0, 654916.0, 60010.69994649999, 60043.39956600378, 60100.39899600948, 60110.39889601048, 60169.69915150396, 1153.1466714631113, 1254.1973137192203, 1359.2116572387793, 1399.8880089592808, 1432.9928350358182, 27124.1862744118, 29084.854575727833, 29811.10566683003, 30785.38429231358, 31135.882376443216, 43779.12441751211, 50198.49801502084, 50759.73100672365, 51093.722656933875, 51119.0, 453248.0, 453905.0, 457141.0, 458261.0, 459561.0, 26653.0, 27295.0, 27323.590146147428, 28151.0, 28194.0, 48147.5185248143, 48304.275435867785, 48782.0, 49119.754401227765, 49777.751111244215, 39239.803800980815, 39441.40837887491, 40399.7980010098, 40416.0622608159, 40979.0, 39365.409518857814, 39641.60358396379, 39696.0, 40589.18821623492, 40826.591734083464, 38770.0307492315, 38952.0, 38970.220595587365, 39002.804985974886, 39656.60343396529, 29280.121596352143, 32633.34733305368, 34329.65670343264, 34859.0, 35591.28817423585, 22319.77680223242, 25790.647215861816, 25853.224403267934, 25927.611085834105, 26298.474030519665], "value": 20215.482627595928, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.97666651667623, 373.6114440981381, 375.1184715917593, 376.45602104958346, 396.2688839091874, 15671.764923526142, 16503.09232992185, 21013.579728405424, 21212.68180977283, 21345.35963921081, 21359.252426165087, 21739.891300543513, 22280.0, 22301.0, 22632.773672263313, 5801.274840644919, 5863.970680146594, 6042.456178943894, 6100.572959892809, 6121.908171377433, 4195.748255104703, 5983.311919129291, 6100.755969761204, 6103.664298463583, 6411.5191360647905, 5681.9431805681825, 5753.5397168226655, 6711.966440167792, 6713.0, 6732.124823772902, 6417.93582064178, 6612.371824676648, 6683.365080317362, 6685.632290224035, 6687.0, 5597.972010139964, 5805.941940580625, 5817.0, 5818.854528636775, 5823.737931793079, 26809.865950670122, 26929.78815953287, 27087.593686094726, 27306.317342066606, 27317.45365092687, 2289.6565515172692, 2450.534398464286, 2464.29767516257, 2465.432950421395, 2895.4353900989317, 16103.03381797096, 16396.426125085563, 16731.83268167315, 17265.050422226774, 17308.653826923517, 1853.5551467647801, 1916.252661462031, 1917.1660327757331, 1959.470942845438, 1988.99005504973, 119242.47953964796, 123295.0, 123845.14232286523, 125921.0, 126018.3699081508, 10647.627333043307, 10852.782944341227, 11335.829962550568, 11342.773144537228, 11634.011109055826, 14022.088564243291, 14025.438982440588, 14033.929830350988, 14048.297585120703, 14076.577702668936, 8273.669053237923, 8334.374921880762, 8396.832063358821, 8506.65973361071, 8661.52361620117, 7412.925870741331, 8047.798805029921, 8448.915510844812, 8475.957620211859, 8639.0, 6118.644321935998, 6567.802965911031, 6790.151231096113, 6916.515843890879, 6935.35581669438, 1691.408007197471, 1728.3345911824038, 1767.1341042889148, 1830.275434132408, 1840.7607011088742, 888.8046525083034, 913.7852604637905, 937.3110763588757, 1050.2595670052594, 1060.8886066963012, 22382.664260036112, 22925.885370573375, 22955.540889182124, 22959.311220663407, 22988.540229195656, 977.6822532676908, 994.6220436234329, 1028.7222449938474, 1035.9015893490107, 1041.2763129624989, 11190.272632278877, 11782.116341274268, 12008.63974080779, 12032.977196938358, 12033.39833008346, 542761.0, 568157.0, 601538.0, 607001.0, 614207.0, 117554.0, 118979.0, 119923.0, 120831.0, 122960.0, 923.884514435709, 1017.760826205841, 1019.3679918450608, 1038.0294424712918, 1040.9375437473775, 14747.55757327282, 18787.90606046961, 20249.29127480531, 20780.896095519427, 21862.562748745255, 54961.1755823655, 56170.43829561652, 57429.71285143548, 58389.70805145947, 58671.0, 78292.60853695695, 79327.0, 80382.59808700919, 80569.0, 81344.59327703323, 5730.312362516524, 5833.591648584728, 7675.884861726967, 8463.36524760633, 8683.609237584198, 12847.678808029501, 13837.515686950908, 13853.960952928772, 14367.425302987764, 14471.348789304297, 6995.055667484824, 8384.99380074395, 9142.0, 10948.56205751761, 11827.107662773915, 10085.445300508543, 11550.249233799776, 11724.120690948042, 11757.588484403004, 12066.0, 11512.30926144434, 11512.827307590225, 11860.525578976743, 12055.939720301692, 12063.939680301542, 8221.260086592236, 8450.788730281793, 8452.577371131296, 8509.617067232113, 8716.520591367536, 11609.88390116088, 11634.941825291158, 11849.466773995018, 11955.462004209658, 11981.64055078349], "value": 9998.12523163591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1582.3749618900536, 1604.2139351717658, 1687.8059023212334, 1871.2234422714573, 1896.6775648139815, 8475.32197424206, 8824.77938051549, 8859.291256699473, 8889.377743557954, 8951.104889511045, 7875.015623047105, 7916.010498687664, 8445.78885527862, 8675.652973881053, 8688.565571721405, 2317.8609283443006, 2335.8481698689616, 2345.777151170636, 2349.7532759060223, 2356.9175078872245, 2305.988470057647, 2332.7667233276707, 2360.398098484884, 2362.385779697274, 2368.8460250083776, 2328.39461739948, 2339.590571649963, 2342.672025916373, 2354.3878591566145, 2369.668246445499, 2307.526956973833, 2321.616933206019, 2329.557384097016, 2331.603627383349, 2335.6730057791924, 2312.814974802012, 2315.6410756332625, 2317.8029867461287, 2325.5000174962465, 2327.5810354136247, 8729.214370706602, 8774.341924355635, 8894.822103557957, 8896.421732587361, 8953.686620968236, 5162.92255616166, 5207.3490813648295, 5325.068113080234, 5380.085385484477, 5395.5953303502, 3244.6917542833394, 3309.271960168759, 3380.5267262583257, 3381.628020917697, 3387.458006718913, 1003.1272792670351, 1046.1369370269558, 1049.1449468683, 1062.8299472084507, 1065.7495488560182, 11466.942665286704, 11506.654800355918, 11527.538898444052, 11745.412729363497, 11751.0, 3784.262068896539, 4649.232876575395, 4720.881977950578, 4738.644601654821, 4751.477337492873, 9771.071748183911, 9814.705558833246, 9978.800423991479, 9985.700428987142, 10014.148797352162, 5262.7631756571045, 5306.09796334624, 5342.065138600729, 5357.08929481989, 5375.435579264199, 4370.147821174841, 4478.888027799266, 4726.456457507362, 4865.197242455026, 4897.41231052276, 3817.6373244541724, 3885.203533275672, 3950.545687245946, 3971.0270983609203, 4012.25773231953, 1099.197585762398, 1198.221156248428, 1221.407617305606, 1230.6861750253806, 1240.6898275431142, 812.3988248695922, 860.9620878081901, 905.1627244798535, 950.334765664038, 983.0513554420019, 5856.443637854397, 5966.880662386728, 6015.0, 6149.84625384369, 6209.410106039919, 420.9917248190566, 439.51872699394113, 443.1425192253001, 443.5475814669084, 451.6973627669469, 3986.421968814506, 4025.5546474774396, 4105.548389677133, 4113.8971525712095, 4125.051238215227, 10278.434686092336, 10508.474576271308, 10573.471326433648, 10621.2034097443, 10705.83941240882, 10607.310524815863, 10608.098311643442, 10672.199585031154, 10693.358398495959, 10708.357498550113, 6997.0, 7940.444168908237, 7982.082060562993, 8293.087760346236, 8313.0, 5860.355360910211, 5944.970275148597, 5956.404359564009, 5970.0746384310505, 5998.070299103646, 10045.748856278651, 10058.446785426871, 10059.748506287402, 10159.644412445528, 10165.84751228747, 10077.344972576759, 10108.54511546997, 10259.333143345659, 10318.43248621303, 10418.062374386342, 6996.720131194898, 7191.137063552407, 7277.16312624055, 7538.924610753822, 7564.394848412004, 8130.715424960098, 8364.623591938256, 8396.832063358577, 8446.239838414573, 8475.872861906953, 7257.165425976081, 7341.192468828318, 7398.371138453292, 7489.850202995801, 7554.433417493819, 7204.135503739585, 7323.450741194325, 7353.338199562065, 7354.448416368687, 7359.153697324876, 6947.166340039228, 6964.79105626832, 6974.895376569254, 7013.824654383681, 7026.648667566704, 4707.8587642370785, 4826.034793041335, 4838.685485443435, 4862.6839255448285, 4916.188828843201, 4802.423709154924, 5094.2103973884105, 5126.4360920297995, 5183.637145399936, 5195.3246078009615], "value": 4474.253746363565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.49165148376477, 308.1787037544942, 320.53362357769447, 323.0518428412611, 323.501807216886, 4295.818649871287, 5146.305248791412, 5167.328247327847, 5631.043702056412, 5634.690092044941, 5187.818426355078, 5255.500727430888, 5298.36419629644, 5407.783688652459, 5478.506934375905, 3432.5537680101615, 4137.055583875589, 4201.642860356873, 4205.915881682365, 4226.852060177895, 3455.9654403455893, 4160.750354978711, 4184.225918205127, 4192.811323490435, 4234.21666991606, 3451.4650229214512, 3487.4943133245665, 3867.8066096695193, 3956.4312813609104, 4200.991761977134, 4115.300398932189, 4128.256913755523, 4129.359949207877, 4137.8551750688885, 4153.501579810412, 3013.8697988254403, 3594.9377203759586, 3636.5999740028574, 3639.9636003640153, 3646.872359467406, 4690.296455531698, 5063.316452278932, 5378.058839703036, 5380.838574842761, 5413.972930135364, 4441.489228738704, 4792.424909010941, 4799.2801079837955, 4803.78382972767, 4848.369711937426, 5625.240592519998, 5720.22776925114, 5744.913826292609, 5767.307923049219, 5849.912251316234, 3561.4301711726, 3580.910477238064, 3834.578196398394, 3863.16941857502, 3881.4371916072296, 4752.217918280645, 5017.713397724497, 5485.259489968843, 6005.699715014275, 6100.542459315569, 4075.409065685459, 4095.447114639545, 4096.856610018635, 4108.055147316134, 4123.958760412417, 4172.70791044624, 4178.331466965271, 4193.475815523059, 4198.250146154274, 4202.768847713405, 3755.3615885299564, 4193.39195816611, 4799.472058073609, 4812.759362031885, 4872.634552408584, 4830.782614782343, 5425.945740542544, 5506.174073888948, 5821.126830975388, 5887.470127688529, 1105.8838821923744, 1353.661584603849, 1421.822272215973, 1440.6326386771307, 1446.305773228835, 881.96809732613, 890.9955450222708, 921.0099143420849, 925.2089463508738, 942.4816351007056, 4410.977945110254, 4462.107578484316, 5332.33345831771, 5342.519173274424, 5362.946370536322, 4955.5044495550155, 5366.114591092426, 5834.970825145932, 5847.3275573308765, 5859.912101318484, 4304.418903447994, 4645.864041771858, 4874.926876096862, 5312.840614781563, 5397.676139431646, 2710.6069619905, 2933.313344826733, 3037.346970401374, 3104.7826652134586, 3106.487429574095, 5511.071125106181, 6043.516518678495, 6054.305833903871, 6060.515158787286, 6064.36324185963, 5760.94239057604, 5996.280446346466, 6122.173506576644, 6198.566100373021, 6242.313345531988, 5187.507186817246, 5787.421257874179, 6241.906371404432, 6244.128640242988, 6248.0, 4977.950220497749, 5032.094223039888, 5739.139129130664, 6012.488938440281, 6093.634381937098, 5080.7205603692155, 5883.941160588511, 5912.201852749996, 6005.339412664516, 6019.819405417845, 5501.587380946525, 5593.720313984366, 6048.183495228264, 6311.873762524816, 6349.269813980033, 3767.9261410497875, 3986.700997425263, 4002.239574480839, 4032.475778148822, 4082.1427500225286, 5586.357568879631, 5970.522358211246, 6033.0, 6080.422359875804, 6088.934436473688, 4612.838550650711, 5082.186850104114, 5095.898082038412, 5467.808626698205, 5830.125481177857, 4905.90188196227, 5185.714785686823, 5399.811006614749, 5771.971140144273, 5890.528757699288, 4765.046331004271, 5435.782568697366, 5689.1750696148, 5791.739371728367, 5817.970910145422, 5582.720863956704, 5730.598858079894, 5802.564807639528, 5870.236869206976, 5882.382349853375, 5173.870653233699, 5177.948220517746, 5281.125200553748, 5690.788816276009, 5748.310202775694], "value": 4178.820116664148, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.2378174262253, 752.7666423408742, 783.7844592736997, 854.8504011797936, 867.7526904832124, 15384.769228461568, 15997.680046399066, 16002.759958600602, 16061.598460038505, 16063.759043614325, 14617.634559136028, 14798.112113273211, 14816.036957597775, 14858.33137508812, 15033.098014119161, 687.226869771507, 707.6850801393382, 719.4783781758209, 849.2611428057583, 885.0618344554761, 874.6020560644891, 877.7937184761576, 878.2798105553449, 878.4290211362602, 878.5343767803042, 913.2967614936495, 927.211869910574, 928.5450129436595, 929.7629104578323, 930.2604429478581, 923.4090182283344, 924.3806649544821, 924.4037595750704, 924.4037595750704, 925.8240934222476, 534.5349545895097, 596.8149873539173, 669.6517810738437, 687.6767919078026, 688.1157712339619, 14453.566393008226, 14493.637659058502, 14521.78217326741, 14526.636834079232, 14540.854591454161, 13298.268595227259, 13844.723105537832, 13852.238126903016, 13861.850943650636, 14049.0, 11206.607768728056, 11766.705832354259, 11823.408829558573, 11851.88148118525, 12315.93842030784, 4463.419755431773, 4519.096180763828, 4643.257078867365, 4661.137689527414, 4717.858464246078, 105730.41404379018, 106731.0, 107566.92433075648, 107567.0, 111442.0, 9318.86021709675, 10734.838977415346, 10818.350898945931, 10889.455527223765, 11165.720856978478, 21172.153113875273, 21367.67948480774, 21377.57244855094, 21404.892975535022, 21504.892475537836, 7742.496737712031, 7781.144074151839, 7793.844123117506, 7794.571298578519, 7955.4431189817315, 4291.249031419489, 4340.110277393127, 4350.934735978963, 4357.172137293904, 4514.25514790056, 3316.403047451482, 3429.8628054877527, 3431.4166591679473, 3457.775244609092, 3460.7058400035776, 642.4667525953447, 654.8395643067383, 658.8846951783419, 669.3908543225641, 671.8320419895026, 594.8334466349429, 596.4786915182904, 600.1897438458046, 634.511426201822, 653.2161406312349, 8763.868541971875, 8884.094234928009, 8914.777130571658, 8915.955420222857, 8917.955410222907, 598.4703537369372, 612.3050337866554, 623.6788054152115, 628.5694299404922, 645.5287640022809, 4267.274563324242, 4283.5502272260965, 4305.16049370376, 4342.609165175149, 4383.802728877208, 98100.0, 149225.0, 149426.0, 151858.0, 154367.0, 108226.0, 133285.3335733315, 134888.0, 136349.0, 138846.0, 117087.0, 118253.0, 120127.0, 120175.39912300557, 122828.0, 8375.664973400995, 8396.286315663237, 8459.450135741157, 8497.532635705096, 8757.0, 64416.03375949458, 64418.0, 64980.0, 66094.33905661074, 66483.66758166178, 96912.0308796903, 99598.0, 100376.49811750895, 100448.49775751075, 100457.0, 11561.421928903354, 11609.245399049034, 11657.766844663229, 12016.63950081499, 12093.57672481459, 12103.213291136048, 12106.092043096627, 12138.089643276966, 12248.632541023784, 12589.496420143092, 11749.236299640495, 11759.88240117622, 11773.941130294294, 11823.52705891789, 11852.466639001093, 11544.422778861192, 11605.0, 11703.180777345504, 11711.648650540497, 12391.62825115248, 11610.767784644091, 11650.825237621273, 11708.063354931415, 12144.028477721939, 12188.634340969786, 9617.519124043909, 9978.800423991624, 10134.847977280495, 10158.339707918962, 10200.897991020292, 8662.393632445668, 9549.245108192152, 9908.554115064695, 10064.949675251577, 10134.189264858942], "value": 7627.893463602964, "unit": "score"}], "name": "dromaeo_css", "value": 7405.764761533475, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 168.0, 162.0, 113.0, 113.0, 112.0, 113.0, 113.0, 111.0, 110.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 106.0, 163.0, 104.0, 107.0, 129.0, 106.0, 138.0, 108.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 184.0, 187.0, 183.0, 182.0, 183.0, 181.0, 184.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 57.0, 104.0, 57.0, 58.0, 61.0, 65.0, 83.0, 73.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 90.0, 69.0, 72.0, 68.0, 96.0, 88.0, 93.0, 118.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 121.0, 90.0, 88.0, 90.0, 87.0, 90.0, 86.0, 90.0, 86.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 96.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 86.0, 86.0, 88.0, 127.0, 87.0, 124.0, 129.0, 92.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 52.0, 53.0, 55.0, 52.0, 52.0, 54.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 73.0, 74.0, 76.0, 72.0, 71.0, 71.0, 70.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 86.0, 86.0, 85.0, 83.0, 84.0, 85.0, 136.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 127.0, 137.0, 123.0, 154.0, 146.0, 151.0, 147.0, 142.0, 137.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 205.0, 226.0, 192.0, 166.0, 165.0, 159.0, 166.0, 168.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1470.1000000000001, "alertThreshold": 2.0}]} 03:27:23 INFO - Return code: 0 03:27:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:27:23 INFO - # TBPL SUCCESS # 03:27:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:27:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:27:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:27:23 INFO - 2016-07-30 03:27:23.146 screenresolution[1680:11831] starting screenresolution argv=screenresolution get 03:27:23 INFO - 2016-07-30 03:27:23.148 screenresolution[1680:11831] Display 0: 1600x1200x32@60 03:27:23 INFO - 2016-07-30 03:27:23.154 screenresolution[1681:11834] starting screenresolution argv=screenresolution list 03:27:23 INFO - Available Modes on Display 0 03:27:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:27:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:27:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:27:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:27:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:27:23 INFO - Intel Iris: 03:27:23 INFO - Chipset Model: Intel Iris 03:27:23 INFO - Type: GPU 03:27:23 INFO - Bus: Built-In 03:27:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:27:23 INFO - Vendor: Intel (0x8086) 03:27:23 INFO - Device ID: 0x0a2e 03:27:23 INFO - Revision ID: 0x0009 03:27:23 INFO - Displays: 03:27:23 INFO - Display: 03:27:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:27:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:27:23 INFO - Display Serial Number: 1600x1200 60 03:27:23 INFO - Main Display: Yes 03:27:23 INFO - Mirror: Off 03:27:23 INFO - Online: Yes 03:27:23 INFO - Rotation: Supported 03:27:23 INFO - Return code: 0 03:27:23 INFO - Running post-action listener: _resource_record_post_action 03:27:23 INFO - [mozharness: 2016-07-30 10:27:23.389966Z] Finished run-tests step (success) 03:27:23 INFO - Running post-run listener: _resource_record_post_run 03:27:23 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31894528; Write bytes: 703860736; Read time: 193; Write time: 2002 03:27:23 INFO - TinderboxPrint: CPU usage
53.4% 03:27:23 INFO - TinderboxPrint: I/O read bytes / time
31,894,528 / 193 03:27:23 INFO - TinderboxPrint: I/O write bytes / time
703,860,736 / 2,002 03:27:23 INFO - TinderboxPrint: CPU idle
2,262.2 (46.6%) 03:27:23 INFO - TinderboxPrint: CPU system
967.4 (19.9%) 03:27:23 INFO - TinderboxPrint: CPU user
1,624.1 (33.5%) 03:27:23 INFO - TinderboxPrint: Swap in / out
432,558,080 / 0 03:27:23 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 390257152; Write bytes: 384427008; Read time: 30270; Write time: 836 03:27:23 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30968832; Write bytes: 296307712; Read time: 182; Write time: 1109 03:27:23 INFO - Running post-run listener: _upload_blobber_files 03:27:23 INFO - Blob upload gear active. 03:27:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:27:23 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:23 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:23 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:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:24 INFO - (blobuploader) - WARNING - File uploaded to blobserver but failed uploading to Amazon S3. 03:27:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:24 INFO - (blobuploader) - INFO - Done attempting. 03:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:25 INFO - (blobuploader) - INFO - Done attempting. 03:27:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:27:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:27:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:28 INFO - (blobuploader) - INFO - Done attempting. 03:27:28 INFO - (blobuploader) - INFO - Iteration through files over. 03:27:28 INFO - Return code: 0 03:27:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:27:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:27:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d609a592c4fb420a81962c18680843498324c3c8965baf15a3fc68e70c1835a96519fb921a8085345dc3c5a55f3e128a5e85b7a19db6f0ece63b97d414adc8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84fdff9d52e30d835b2351a02afda2f757b17ea7400b50efb7aa713067fde13c26ee6dda202fa7694279e27f0cd78b731434558e20e853aa6b3276feccac630b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fc22e2961ebce5f6f83f8a4862f33db192c58c069ec3269aa61e1a8ba0ae318562075e7e7917124331349a620dd990736c566fe4e1dd6d2414286c256634b05"} 03:27:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:27:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:27:28 INFO - Contents: 03:27:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d609a592c4fb420a81962c18680843498324c3c8965baf15a3fc68e70c1835a96519fb921a8085345dc3c5a55f3e128a5e85b7a19db6f0ece63b97d414adc8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84fdff9d52e30d835b2351a02afda2f757b17ea7400b50efb7aa713067fde13c26ee6dda202fa7694279e27f0cd78b731434558e20e853aa6b3276feccac630b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fc22e2961ebce5f6f83f8a4862f33db192c58c069ec3269aa61e1a8ba0ae318562075e7e7917124331349a620dd990736c566fe4e1dd6d2414286c256634b05"} 03:27:28 INFO - Running post-run listener: copy_logs_to_upload_dir 03:27:28 INFO - Copying logs to upload dir... 03:27:28 INFO - mkdir: /builds/slave/test/build/upload/logs 03:27:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.051937 ========= master_lag: 6.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-07-30 03:27:35.103444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-07-30 03:27:35.107376) ========= 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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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/d609a592c4fb420a81962c18680843498324c3c8965baf15a3fc68e70c1835a96519fb921a8085345dc3c5a55f3e128a5e85b7a19db6f0ece63b97d414adc8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84fdff9d52e30d835b2351a02afda2f757b17ea7400b50efb7aa713067fde13c26ee6dda202fa7694279e27f0cd78b731434558e20e853aa6b3276feccac630b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fc22e2961ebce5f6f83f8a4862f33db192c58c069ec3269aa61e1a8ba0ae318562075e7e7917124331349a620dd990736c566fe4e1dd6d2414286c256634b05"} 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.011116 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/d609a592c4fb420a81962c18680843498324c3c8965baf15a3fc68e70c1835a96519fb921a8085345dc3c5a55f3e128a5e85b7a19db6f0ece63b97d414adc8a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84fdff9d52e30d835b2351a02afda2f757b17ea7400b50efb7aa713067fde13c26ee6dda202fa7694279e27f0cd78b731434558e20e853aa6b3276feccac630b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fc22e2961ebce5f6f83f8a4862f33db192c58c069ec3269aa61e1a8ba0ae318562075e7e7917124331349a620dd990736c566fe4e1dd6d2414286c256634b05"}' ========= master_lag: 4.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-07-30 03:27:39.179273) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:39.179571) ========= 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.QveWl4GVis/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.iEvQTtmeX9/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005376 ========= master_lag: 0.85 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:40.031859) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-07-30 03:27:40.032161) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-07-30 03:27:43.766310) ========= ========= Total master_lag: 11.26 =========