builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0282 starttime: 1490221997.99 results: success (0) buildid: 20170322124436 builduid: 5f14176237354e34944af11350a2af85 revision: 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:17.988262) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:17.988727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:17.989006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:18.023392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:18.023821) ========= 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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 --2017-03-22 15:33:18-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.85M=0.002s 2017-03-22 15:33:18 (6.85 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:18.175241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:18.175644) ========= 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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.035428 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:18.234126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 15:33:18.234556) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 2017-03-22 15:33:18,421 truncating revision to first 12 chars 2017-03-22 15:33:18,422 Setting DEBUG logging. 2017-03-22 15:33:18,422 attempt 1/10 2017-03-22 15:33:18,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/09dd29795f18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 15:33:20,608 unpacking tar archive at: pine-09dd29795f18/testing/mozharness/ program finished with exit code 0 elapsedTime=2.451858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 15:33:20.710943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:20.711331) ========= script_repo_revision: 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:20.711763) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:20.712025) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 15:33:20.732468) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-22 15:33:20.732861) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:33:21 INFO - MultiFileLogger online at 20170322 15:33:21 in /builds/slave/test 15:33:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 15:33:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:21 INFO - {'append_to_log': False, 15:33:21 INFO - 'base_work_dir': '/builds/slave/test', 15:33:21 INFO - 'blob_upload_branch': 'Pine', 15:33:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:21 INFO - 'branch': 'Pine', 15:33:21 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:21 INFO - 'config_files': ('talos/mac_config.py',), 15:33:21 INFO - 'default_actions': ('clobber', 15:33:21 INFO - 'read-buildbot-config', 15:33:21 INFO - 'download-and-extract', 15:33:21 INFO - 'populate-webroot', 15:33:21 INFO - 'create-virtualenv', 15:33:21 INFO - 'install', 15:33:21 INFO - 'run-tests'), 15:33:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:21 INFO - 'download_minidump_stackwalk': True, 15:33:21 INFO - 'download_symbols': 'ondemand', 15:33:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:21 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:21 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:21 INFO - 'gecko_profile': False, 15:33:21 INFO - 'gecko_profile_interval': 0, 15:33:21 INFO - 'installer_path': 'installer.exe', 15:33:21 INFO - 'log_level': 'info', 15:33:21 INFO - 'log_name': 'talos', 15:33:21 INFO - 'log_to_console': True, 15:33:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:33:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:33:21 INFO - 'opt_config_files': (), 15:33:21 INFO - 'pip_index': False, 15:33:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:21 INFO - 'cmd': ('bash', 15:33:21 INFO - '-c', 15:33:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:21 INFO - 'enabled': True, 15:33:21 INFO - 'halt_on_failure': False, 15:33:21 INFO - 'name': 'check_screen_resolution'},), 15:33:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:21 INFO - 'cmd': ('bash', 15:33:21 INFO - '-c', 15:33:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:21 INFO - 'enabled': True, 15:33:21 INFO - 'halt_on_failure': False, 15:33:21 INFO - 'name': 'check_screen_resolution'},), 15:33:21 INFO - 'run_cmd_checks_enabled': True, 15:33:21 INFO - 'suite': 'dromaeojs', 15:33:21 INFO - 'system_bits': '32', 15:33:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:21 INFO - 'title': 't-yosemite-r7-0282', 15:33:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:21 INFO - 'use_talos_json': True, 15:33:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:21 INFO - 'work_dir': 'build'} 15:33:21 INFO - [mozharness: 2017-03-22 22:33:21.036403Z] Running clobber step. 15:33:21 INFO - Running pre-action listener: _resource_record_pre_action 15:33:21 INFO - Running main action method: clobber 15:33:21 INFO - rmtree: /builds/slave/test/build 15:33:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:22 INFO - Running post-action listener: _resource_record_post_action 15:33:22 INFO - [mozharness: 2017-03-22 22:33:22.835099Z] Finished clobber step (success) 15:33:22 INFO - [mozharness: 2017-03-22 22:33:22.835231Z] Running read-buildbot-config step. 15:33:22 INFO - Running pre-action listener: _resource_record_pre_action 15:33:22 INFO - Running main action method: read_buildbot_config 15:33:22 INFO - Using buildbot properties: 15:33:22 INFO - { 15:33:22 INFO - "project": "", 15:33:22 INFO - "product": "firefox", 15:33:22 INFO - "script_repo_revision": "default", 15:33:22 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 15:33:22 INFO - "repository": "", 15:33:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 15:33:22 INFO - "buildid": "20170322124436", 15:33:22 INFO - "pgo_build": "False", 15:33:22 INFO - "basedir": "/builds/slave/test", 15:33:22 INFO - "buildnumber": 4, 15:33:22 INFO - "slavename": "t-yosemite-r7-0282", 15:33:22 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:33:22 INFO - "platform": "yosemite_r7", 15:33:22 INFO - "scheduler": "tests-pine-macosx64-talos", 15:33:22 INFO - "branch": "pine", 15:33:22 INFO - "repo_path": "projects/pine", 15:33:22 INFO - "revision": "09dd29795f1878f488a59f5a7c6d12b1b91a68b4", 15:33:22 INFO - "stage_platform": "macosx64", 15:33:22 INFO - "builduid": "5f14176237354e34944af11350a2af85", 15:33:22 INFO - "slavebuilddir": "test" 15:33:22 INFO - } 15:33:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:33:22 INFO - Running post-action listener: _resource_record_post_action 15:33:22 INFO - [mozharness: 2017-03-22 22:33:22.838570Z] Finished read-buildbot-config step (success) 15:33:22 INFO - [mozharness: 2017-03-22 22:33:22.838658Z] Running download-and-extract step. 15:33:22 INFO - Running pre-action listener: _resource_record_pre_action 15:33:22 INFO - Running main action method: download_and_extract 15:33:22 INFO - mkdir: /builds/slave/test/build/tests 15:33:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:33:22 INFO - trying https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:33:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:33:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:33:24 INFO - Downloaded 1325 bytes. 15:33:24 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:33:24 INFO - Using the following test package requirements: 15:33:24 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:33:24 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:33:24 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:33:24 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:33:24 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'jsshell-mac.zip'], 15:33:24 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:33:24 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:33:24 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:33:24 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:33:24 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:33:24 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:33:24 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:33:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:33:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:33:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:33:24 INFO - Fetch https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:33:25 INFO - Expected file size: 52400847 15:33:25 INFO - Obtained file size: 52400847 15:33:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:33:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:33:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:33:41 INFO - Fetch https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:33:43 INFO - Expected file size: 52400847 15:33:43 INFO - Obtained file size: 52400847 15:33:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:33:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:33:58 INFO - Fetch https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:33:59 INFO - Expected file size: 14404171 15:33:59 INFO - Obtained file size: 14404171 15:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:00 INFO - trying https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:34:02 INFO - Downloaded 75391357 bytes. 15:34:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:34:02 INFO - mkdir: /builds/slave/test/properties 15:34:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:34:02 INFO - Writing to file /builds/slave/test/properties/build_url 15:34:02 INFO - Contents: 15:34:02 INFO - build_url:https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:34:02 INFO - Running post-action listener: _resource_record_post_action 15:34:02 INFO - Running post-action listener: set_extra_try_arguments 15:34:02 INFO - [mozharness: 2017-03-22 22:34:02.853703Z] Finished download-and-extract step (success) 15:34:02 INFO - [mozharness: 2017-03-22 22:34:02.853846Z] Running populate-webroot step. 15:34:02 INFO - Running pre-action listener: _resource_record_pre_action 15:34:02 INFO - Running main action method: populate_webroot 15:34:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:34:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:34:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:34:02 INFO - '--tppagecycles', 15:34:02 INFO - '7'], 15:34:02 INFO - 'tests': ['tsvgm']}}, 15:34:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:34:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:34:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:34:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:34:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['damp', 'tps']}, 15:34:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'tests': ['damp', 'tps']}, 15:34:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['dromaeo_dom']}, 15:34:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:34:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['basic_compositor_video']}, 15:34:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:34:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'other-e10s': {'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['a11yr', 15:34:02 INFO - 'ts_paint', 15:34:02 INFO - 'tpaint', 15:34:02 INFO - 'sessionrestore', 15:34:02 INFO - 'sessionrestore_no_auto_restore', 15:34:02 INFO - 'tabpaint']}, 15:34:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['tsvgx', 15:34:02 INFO - 'tsvgr_opacity', 15:34:02 INFO - 'tart', 15:34:02 INFO - 'tscrollx', 15:34:02 INFO - 'cart', 15:34:02 INFO - 'tsvg_static']}, 15:34:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:34:02 INFO - 'tsvgr_opacity', 15:34:02 INFO - 'tart', 15:34:02 INFO - 'tscrollx', 15:34:02 INFO - 'cart', 15:34:02 INFO - 'tsvg_static']}, 15:34:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'talos_options': ['--disable-e10s'], 15:34:02 INFO - 'tests': ['tp5o']}, 15:34:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:34:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'talos_options': ['--disable-e10s', 15:34:02 INFO - '--xperf_path', 15:34:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:02 INFO - 'tests': ['tp5n']}, 15:34:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:34:02 INFO - 'talos_options': ['--xperf_path', 15:34:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:02 INFO - 'tests': ['tp5n']}}, 15:34:02 INFO - 'talos.zip': {'path': '', 15:34:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:34:02 INFO - Running post-action listener: _resource_record_post_action 15:34:02 INFO - [mozharness: 2017-03-22 22:34:02.871986Z] Finished populate-webroot step (success) 15:34:02 INFO - [mozharness: 2017-03-22 22:34:02.872096Z] Running create-virtualenv step. 15:34:02 INFO - Running pre-action listener: _resource_record_pre_action 15:34:02 INFO - Running main action method: create_virtualenv 15:34:02 INFO - Creating virtualenv /builds/slave/test/build/venv 15:34:02 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 15:34:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:34:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:34:02 INFO - Using real prefix '/tools/python27' 15:34:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:34:03 INFO - Installing distribute.............................................................................................................................................................................................done. 15:34:06 INFO - Installing pip.................done. 15:34:06 INFO - Return code: 0 15:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:34:06 INFO - Reading from file tmpfile_stdout 15:34:06 INFO - Output received: 15:34:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:34:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:34:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:06 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:06 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 15:34:06 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 15:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:06 INFO - 'HOME': '/Users/cltbld', 15:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:06 INFO - 'LOGNAME': 'cltbld', 15:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:06 INFO - 'MOZ_NO_REMOTE': '1', 15:34:06 INFO - 'NO_EM_RESTART': '1', 15:34:06 INFO - 'PAGER': '/bin/cat', 15:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:06 INFO - 'PWD': '/builds/slave/test', 15:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:06 INFO - 'SHELL': '/bin/bash', 15:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:06 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:06 INFO - 'USER': 'cltbld', 15:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:06 INFO - 'XPC_FLAGS': '0x0', 15:34:06 INFO - 'XPC_SERVICE_NAME': '0', 15:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:07 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 15:34:07 INFO - Cleaning up... 15:34:07 INFO - Return code: 0 15:34:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:34:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:07 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:07 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 15:34:07 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 15:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:07 INFO - 'HOME': '/Users/cltbld', 15:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:07 INFO - 'LOGNAME': 'cltbld', 15:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:07 INFO - 'MOZ_NO_REMOTE': '1', 15:34:07 INFO - 'NO_EM_RESTART': '1', 15:34:07 INFO - 'PAGER': '/bin/cat', 15:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:07 INFO - 'PWD': '/builds/slave/test', 15:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:07 INFO - 'SHELL': '/bin/bash', 15:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:07 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:07 INFO - 'USER': 'cltbld', 15:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:07 INFO - 'XPC_FLAGS': '0x0', 15:34:07 INFO - 'XPC_SERVICE_NAME': '0', 15:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:07 INFO - Downloading/unpacking psutil>=3.1.1 15:34:07 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 15:34:07 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 15:34:07 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 15:34:07 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 15:34:07 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 15:34:07 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 15:34:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:34:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:11 INFO - Installing collected packages: psutil 15:34:11 INFO - Running setup.py install for psutil 15:34:11 INFO - building 'psutil._psutil_osx' extension 15:34:11 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 15:34:11 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 15:34:11 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 15:34:11 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 15:34:11 INFO - building 'psutil._psutil_posix' extension 15:34:11 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 15:34:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:34:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:34:11 INFO - ^ 15:34:11 INFO - 1 warning generated. 15:34:11 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 15:34:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:11 INFO - Successfully installed psutil 15:34:11 INFO - Cleaning up... 15:34:12 INFO - Return code: 0 15:34:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:34:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:12 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 15:34:12 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 15:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:12 INFO - 'HOME': '/Users/cltbld', 15:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:12 INFO - 'LOGNAME': 'cltbld', 15:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:12 INFO - 'MOZ_NO_REMOTE': '1', 15:34:12 INFO - 'NO_EM_RESTART': '1', 15:34:12 INFO - 'PAGER': '/bin/cat', 15:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:12 INFO - 'PWD': '/builds/slave/test', 15:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:12 INFO - 'SHELL': '/bin/bash', 15:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:12 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:12 INFO - 'USER': 'cltbld', 15:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:12 INFO - 'XPC_FLAGS': '0x0', 15:34:12 INFO - 'XPC_SERVICE_NAME': '0', 15:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:34:12 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 15:34:12 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 15:34:12 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 15:34:12 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 15:34:12 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 15:34:12 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 15:34:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:34:15 INFO - Installing collected packages: mozsystemmonitor 15:34:15 INFO - Running setup.py install for mozsystemmonitor 15:34:15 INFO - Successfully installed mozsystemmonitor 15:34:15 INFO - Cleaning up... 15:34:15 INFO - Return code: 0 15:34:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:34:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:16 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:16 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 15:34:16 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 15:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:16 INFO - 'HOME': '/Users/cltbld', 15:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:16 INFO - 'LOGNAME': 'cltbld', 15:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:16 INFO - 'MOZ_NO_REMOTE': '1', 15:34:16 INFO - 'NO_EM_RESTART': '1', 15:34:16 INFO - 'PAGER': '/bin/cat', 15:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:16 INFO - 'PWD': '/builds/slave/test', 15:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:16 INFO - 'SHELL': '/bin/bash', 15:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:16 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:16 INFO - 'USER': 'cltbld', 15:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:16 INFO - 'XPC_FLAGS': '0x0', 15:34:16 INFO - 'XPC_SERVICE_NAME': '0', 15:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:16 INFO - Downloading/unpacking jsonschema==2.5.1 15:34:16 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 15:34:16 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 15:34:16 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 15:34:16 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 15:34:16 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 15:34:16 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 15:34:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:34:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:34:19 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 15:34:19 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 15:34:19 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 15:34:19 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 15:34:19 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 15:34:19 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 15:34:19 INFO - Downloading functools32-3.2.3-2.tar.gz 15:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:34:19 INFO - warning: no files found matching '*.txt' 15:34:19 INFO - no previously-included directories found matching 'build' 15:34:19 INFO - no previously-included directories found matching 'dist' 15:34:19 INFO - no previously-included directories found matching '.git*' 15:34:19 INFO - Installing collected packages: jsonschema, functools32 15:34:19 INFO - Running setup.py install for functools32 15:34:19 INFO - warning: no files found matching '*.txt' 15:34:19 INFO - no previously-included directories found matching 'build' 15:34:19 INFO - no previously-included directories found matching 'dist' 15:34:19 INFO - no previously-included directories found matching '.git*' 15:34:19 INFO - Successfully installed jsonschema functools32 15:34:19 INFO - Cleaning up... 15:34:19 INFO - Return code: 0 15:34:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:34:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:19 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:19 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 15:34:19 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 15:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:19 INFO - 'HOME': '/Users/cltbld', 15:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:19 INFO - 'LOGNAME': 'cltbld', 15:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:19 INFO - 'MOZ_NO_REMOTE': '1', 15:34:19 INFO - 'NO_EM_RESTART': '1', 15:34:19 INFO - 'PAGER': '/bin/cat', 15:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:19 INFO - 'PWD': '/builds/slave/test', 15:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:19 INFO - 'SHELL': '/bin/bash', 15:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:19 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:19 INFO - 'USER': 'cltbld', 15:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:19 INFO - 'XPC_FLAGS': '0x0', 15:34:19 INFO - 'XPC_SERVICE_NAME': '0', 15:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:34:20 INFO - Cleaning up... 15:34:20 INFO - Return code: 0 15:34:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:20 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:20 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 15:34:20 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 15:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:20 INFO - 'HOME': '/Users/cltbld', 15:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:20 INFO - 'LOGNAME': 'cltbld', 15:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:20 INFO - 'MOZ_NO_REMOTE': '1', 15:34:20 INFO - 'NO_EM_RESTART': '1', 15:34:20 INFO - 'PAGER': '/bin/cat', 15:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:20 INFO - 'PWD': '/builds/slave/test', 15:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:20 INFO - 'SHELL': '/bin/bash', 15:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:20 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:20 INFO - 'USER': 'cltbld', 15:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:20 INFO - 'XPC_FLAGS': '0x0', 15:34:20 INFO - 'XPC_SERVICE_NAME': '0', 15:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:20 INFO - Downloading/unpacking blobuploader==1.2.4 15:34:20 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 15:34:20 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 15:34:20 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 15:34:20 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 15:34:20 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 15:34:20 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 15:34:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:34:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:34:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:24 INFO - Downloading docopt-0.6.1.tar.gz 15:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:34:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:34:24 INFO - Running setup.py install for blobuploader 15:34:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:34:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:34:24 INFO - Running setup.py install for requests 15:34:25 INFO - Running setup.py install for docopt 15:34:25 INFO - Successfully installed blobuploader requests docopt 15:34:25 INFO - Cleaning up... 15:34:25 INFO - Return code: 0 15:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:25 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 15:34:25 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 15:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:25 INFO - 'HOME': '/Users/cltbld', 15:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:25 INFO - 'LOGNAME': 'cltbld', 15:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:25 INFO - 'MOZ_NO_REMOTE': '1', 15:34:25 INFO - 'NO_EM_RESTART': '1', 15:34:25 INFO - 'PAGER': '/bin/cat', 15:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:25 INFO - 'PWD': '/builds/slave/test', 15:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:25 INFO - 'SHELL': '/bin/bash', 15:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:25 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:25 INFO - 'USER': 'cltbld', 15:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:25 INFO - 'XPC_FLAGS': '0x0', 15:34:25 INFO - 'XPC_SERVICE_NAME': '0', 15:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:25 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-HF6Kjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:25 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-oJy0ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:25 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-pprbZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:25 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-t8iFdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-5tepXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-iQ1wzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-TkmXXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-T69XUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-4bMaNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-HueQrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-vdxp2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-sIUA_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:26 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-no7DwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gLHS4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_ph8d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-RIKc0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:27 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-dBGDtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:34:27 INFO - Running setup.py install for manifestparser 15:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:34:27 INFO - Running setup.py install for mozcrash 15:34:27 INFO - Running setup.py install for mozdebug 15:34:27 INFO - Running setup.py install for mozdevice 15:34:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozfile 15:34:28 INFO - Running setup.py install for mozhttpd 15:34:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozinfo 15:34:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozInstall 15:34:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozleak 15:34:28 INFO - Running setup.py install for mozlog 15:34:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for moznetwork 15:34:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Running setup.py install for mozprocess 15:34:29 INFO - Running setup.py install for mozprofile 15:34:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Running setup.py install for mozrunner 15:34:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Running setup.py install for mozscreenshot 15:34:29 INFO - Running setup.py install for moztest 15:34:29 INFO - Running setup.py install for mozversion 15:34:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:34:29 INFO - Cleaning up... 15:34:30 INFO - Return code: 0 15:34:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:30 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:30 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 15:34:30 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 15:34:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:30 INFO - 'HOME': '/Users/cltbld', 15:34:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:30 INFO - 'LOGNAME': 'cltbld', 15:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:30 INFO - 'MOZ_NO_REMOTE': '1', 15:34:30 INFO - 'NO_EM_RESTART': '1', 15:34:30 INFO - 'PAGER': '/bin/cat', 15:34:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:30 INFO - 'PWD': '/builds/slave/test', 15:34:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:30 INFO - 'SHELL': '/bin/bash', 15:34:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:30 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:30 INFO - 'USER': 'cltbld', 15:34:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:30 INFO - 'XPC_FLAGS': '0x0', 15:34:30 INFO - 'XPC_SERVICE_NAME': '0', 15:34:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-OyuyNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-9M9twE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-l6Lvm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-bGw0iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-RvfxLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_3yWQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:30 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-c9bZWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-FAb_6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-hRARQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-ka9B7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-8MlEzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-vI5Uaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-ulh2nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-S5cYBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-ZXSWed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-AV2Xjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:31 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Whgiy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:32 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)) 15:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:34:32 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)) 15:34:32 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:34:32 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:34:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:34:32 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 15:34:32 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 15:34:32 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 15:34:32 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 15:34:32 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 15:34:32 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 15:34:34 INFO - Downloading blessings-1.6.tar.gz 15:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:34 INFO - Installing collected packages: blessings 15:34:34 INFO - Running setup.py install for blessings 15:34:34 INFO - Successfully installed blessings 15:34:34 INFO - Cleaning up... 15:34:35 INFO - Return code: 0 15:34:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:35 INFO - Reading from file tmpfile_stdout 15:34:35 INFO - Current package versions: 15:34:35 INFO - blessings == 1.6 15:34:35 INFO - blobuploader == 1.2.4 15:34:35 INFO - docopt == 0.6.1 15:34:35 INFO - functools32 == 3.2.3-2 15:34:35 INFO - jsonschema == 2.5.1 15:34:35 INFO - manifestparser == 1.1 15:34:35 INFO - mozInstall == 1.12 15:34:35 INFO - mozcrash == 1.0 15:34:35 INFO - mozdebug == 0.1 15:34:35 INFO - mozdevice == 0.49 15:34:35 INFO - mozfile == 1.2 15:34:35 INFO - mozhttpd == 0.7 15:34:35 INFO - mozinfo == 0.9 15:34:35 INFO - mozleak == 0.1 15:34:35 INFO - mozlog == 3.4 15:34:35 INFO - moznetwork == 0.27 15:34:35 INFO - mozprocess == 0.25 15:34:35 INFO - mozprofile == 0.28 15:34:35 INFO - mozrunner == 6.13 15:34:35 INFO - mozscreenshot == 0.1 15:34:35 INFO - mozsystemmonitor == 0.3 15:34:35 INFO - moztest == 0.8 15:34:35 INFO - mozversion == 1.4 15:34:35 INFO - psutil == 3.1.1 15:34:35 INFO - requests == 1.2.3 15:34:35 INFO - wsgiref == 0.1.2 15:34:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:35 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:35 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 15:34:35 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 15:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:35 INFO - 'HOME': '/Users/cltbld', 15:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:35 INFO - 'LOGNAME': 'cltbld', 15:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:35 INFO - 'MOZ_NO_REMOTE': '1', 15:34:35 INFO - 'NO_EM_RESTART': '1', 15:34:35 INFO - 'PAGER': '/bin/cat', 15:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:35 INFO - 'PWD': '/builds/slave/test', 15:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:35 INFO - 'SHELL': '/bin/bash', 15:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:35 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:35 INFO - 'USER': 'cltbld', 15:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:35 INFO - 'XPC_FLAGS': '0x0', 15:34:35 INFO - 'XPC_SERVICE_NAME': '0', 15:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 INFO - Cleaning up... 15:34:35 INFO - Return code: 0 15:34:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:35 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 0x10c933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d347d780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, '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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 15:34:35 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 15:34:35 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 15:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:35 INFO - 'HOME': '/Users/cltbld', 15:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:35 INFO - 'LOGNAME': 'cltbld', 15:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:35 INFO - 'MOZ_NO_REMOTE': '1', 15:34:35 INFO - 'NO_EM_RESTART': '1', 15:34:35 INFO - 'PAGER': '/bin/cat', 15:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:35 INFO - 'PWD': '/builds/slave/test', 15:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:35 INFO - 'SHELL': '/bin/bash', 15:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:35 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:35 INFO - 'USER': 'cltbld', 15:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:35 INFO - 'XPC_FLAGS': '0x0', 15:34:35 INFO - 'XPC_SERVICE_NAME': '0', 15:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:34:35 INFO - Cleaning up... 15:34:35 INFO - Return code: 0 15:34:35 INFO - Running post-action listener: _resource_record_post_action 15:34:35 INFO - Running post-action listener: _start_resource_monitoring 15:34:35 INFO - Starting resource monitoring. 15:34:35 INFO - [mozharness: 2017-03-22 22:34:35.856024Z] Finished create-virtualenv step (success) 15:34:35 INFO - [mozharness: 2017-03-22 22:34:35.857051Z] Running install step. 15:34:35 INFO - Running pre-action listener: _resource_record_pre_action 15:34:35 INFO - Running main action method: install 15:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:36 INFO - Reading from file tmpfile_stdout 15:34:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:36 INFO - Reading from file tmpfile_stdout 15:34:36 INFO - Output received: 15:34:36 INFO - Usage: mozinstall [options] installer 15:34:36 INFO - Options: 15:34:36 INFO - -h, --help show this help message and exit 15:34:36 INFO - -d DEST, --destination=DEST 15:34:36 INFO - Directory to install application into. [default: 15:34:36 INFO - "/builds/slave/test"] 15:34:36 INFO - --app=APP Application being installed. [default: firefox] 15:34:36 INFO - mkdir: /builds/slave/test/build/application 15:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:34:56 INFO - Reading from file tmpfile_stdout 15:34:56 INFO - Output received: 15:34:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:34:56 INFO - Running post-action listener: _resource_record_post_action 15:34:56 INFO - [mozharness: 2017-03-22 22:34:56.636255Z] Finished install step (success) 15:34:56 INFO - [mozharness: 2017-03-22 22:34:56.636383Z] Running run-tests step. 15:34:56 INFO - Running pre-action listener: _resource_record_pre_action 15:34:56 INFO - Running main action method: run_tests 15:34:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:34:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:34:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:34:56 INFO - 2017-03-22 15:34:56.651 screenresolution[85334:736857] starting screenresolution argv=screenresolution get 15:34:56 INFO - 2017-03-22 15:34:56.653 screenresolution[85334:736857] Display 0: 1600x1200x32@60 15:34:56 INFO - 2017-03-22 15:34:56.659 screenresolution[85335:736859] starting screenresolution argv=screenresolution list 15:34:56 INFO - Available Modes on Display 0 15:34:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:34:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:34:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:34:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:34:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:34:56 INFO - Intel Iris: 15:34:56 INFO - Chipset Model: Intel Iris 15:34:56 INFO - Type: GPU 15:34:56 INFO - Bus: Built-In 15:34:56 INFO - VRAM (Dynamic, Max): 1536 MB 15:34:56 INFO - Vendor: Intel (0x8086) 15:34:56 INFO - Device ID: 0x0a2e 15:34:56 INFO - Revision ID: 0x0009 15:34:56 INFO - Displays: 15:34:56 INFO - Display: 15:34:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:34:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:34:56 INFO - Display Serial Number: 1600x1200 60 15:34:56 INFO - Main Display: Yes 15:34:56 INFO - Mirror: Off 15:34:56 INFO - Online: Yes 15:34:56 INFO - Rotation: Supported 15:34:56 INFO - Return code: 0 15:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:34:56 INFO - Python 2.7.3 15:34:56 INFO - Return code: 0 15:34:56 INFO - grabbing minidump binary from tooltool 15:34:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:56 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 0x10c711d30>, '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 0x10c638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c706be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:34:56 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 15:34:56 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 15:34:56 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 15:34:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:34:57 INFO - Return code: 0 15:34:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:34:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:34:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:34:57 INFO - ENV: RUST_BACKTRACE is now 1 15:34:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:34:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:34:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 15:34:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0282 --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 15:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zqDUCGuPLp/Render', 15:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:57 INFO - 'HOME': '/Users/cltbld', 15:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:57 INFO - 'LOGNAME': 'cltbld', 15:34:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:34:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:57 INFO - 'MOZ_NO_REMOTE': '1', 15:34:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:34:57 INFO - 'NO_EM_RESTART': '1', 15:34:57 INFO - 'PAGER': '/bin/cat', 15:34:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:57 INFO - 'PWD': '/builds/slave/test', 15:34:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:57 INFO - 'RUST_BACKTRACE': '1', 15:34:57 INFO - 'SHELL': '/bin/bash', 15:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZdwoyEAZSm/Listeners', 15:34:57 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 15:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:57 INFO - 'USER': 'cltbld', 15:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:57 INFO - 'XPC_FLAGS': '0x0', 15:34:57 INFO - 'XPC_SERVICE_NAME': '0', 15:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 15:34:57 INFO - mozversion application_buildid: 20170322124436 15:34:57 INFO - mozversion application_changeset: 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 15:34:57 INFO - mozversion application_display_name: Nightly 15:34:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:34:57 INFO - mozversion application_name: Firefox 15:34:57 INFO - mozversion application_remotingname: firefox 15:34:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 15:34:57 INFO - mozversion application_vendor: Mozilla 15:34:57 INFO - mozversion application_version: 55.0a1 15:34:57 INFO - mozversion platform_buildid: 20170322124436 15:34:57 INFO - mozversion platform_changeset: 09dd29795f1878f488a59f5a7c6d12b1b91a68b4 15:34:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 15:34:57 INFO - mozversion platform_version: 55.0a1 15:34:57 INFO - using testdate: 1490222097 15:34:57 INFO - actual date: 1490222097 15:34:57 INFO - starting webserver on 'localhost:61287' 15:34:57 INFO - SUITE-START | Running 2 tests 15:34:57 INFO - TEST-START | dromaeo_css 15:34:57 INFO - operating with platform_type : mac_ 15:34:57 INFO - Initialising browser for dromaeo_css test... 15:34:57 INFO - TEST-INFO | started process 85345 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpkeMDyA/profile http://localhost:61287/getInfo.html) 15:34:57 INFO - PID 85345 | 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "TopSites": { 15:34:57 INFO - PID 85345 | "rows": [ 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "Facebook", 15:34:57 INFO - PID 85345 | "url": "https://www.facebook.com/" 15:34:57 INFO - PID 85345 | }, 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "YouTube", 15:34:57 INFO - PID 85345 | "url": "https://www.youtube.com/" 15:34:57 INFO - PID 85345 | }, 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "Amazon", 15:34:57 INFO - PID 85345 | "url": "http://www.amazon.com/" 15:34:57 INFO - PID 85345 | }, 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "Yahoo", 15:34:57 INFO - PID 85345 | "url": "https://www.yahoo.com/" 15:34:57 INFO - PID 85345 | }, 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "eBay", 15:34:57 INFO - PID 85345 | "url": "http://www.ebay.com" 15:34:57 INFO - PID 85345 | }, 15:34:57 INFO - PID 85345 | { 15:34:57 INFO - PID 85345 | "title": "Twitter", 15:34:57 INFO - PID 85345 | "url": "https://twitter.com/" 15:34:57 INFO - PID 85345 | } 15:34:57 INFO - PID 85345 | ] 15:34:57 INFO - PID 85345 | } 15:34:57 INFO - PID 85345 | } 15:34:59 INFO - PID 85345 | __metrics Screen width/height:1600/1200 15:34:59 INFO - PID 85345 | colorDepth:24 15:34:59 INFO - PID 85345 | Browser inner width/height: 1024/689 15:34:59 INFO - PID 85345 | __metrics 15:35:00 INFO - PID 85345 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:35:00 INFO - PID 85345 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:35:00 INFO - TEST-INFO | 85345: exit 0 15:35:00 INFO - Browser initialized. 15:35:00 INFO - Running cycle 1/1 for dromaeo_css test... 15:35:00 INFO - TEST-INFO | started process 85347 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpkeMDyA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:01 INFO - PID 85347 | 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "TopSites": { 15:35:01 INFO - PID 85347 | "rows": [ 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "Facebook", 15:35:01 INFO - PID 85347 | "url": "https://www.facebook.com/" 15:35:01 INFO - PID 85347 | }, 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "YouTube", 15:35:01 INFO - PID 85347 | "url": "https://www.youtube.com/" 15:35:01 INFO - PID 85347 | }, 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "Amazon", 15:35:01 INFO - PID 85347 | "url": "http://www.amazon.com/" 15:35:01 INFO - PID 85347 | }, 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "Yahoo", 15:35:01 INFO - PID 85347 | "url": "https://www.yahoo.com/" 15:35:01 INFO - PID 85347 | }, 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "eBay", 15:35:01 INFO - PID 85347 | "url": "http://www.ebay.com" 15:35:01 INFO - PID 85347 | }, 15:35:01 INFO - PID 85347 | { 15:35:01 INFO - PID 85347 | "title": "Twitter", 15:35:01 INFO - PID 85347 | "url": "https://twitter.com/" 15:35:01 INFO - PID 85347 | } 15:35:01 INFO - PID 85347 | ] 15:35:01 INFO - PID 85347 | } 15:35:01 INFO - PID 85347 | } 15:35:13 INFO - PID 85347 | RSS: Main: 234123264 15:35:13 INFO - PID 85347 | 15:38:17 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61287/tests/dromaeo/cssquery-ext.html) 15:38:19 INFO - PID 85347 | RSS: Main: 386011136 15:38:19 INFO - PID 85347 | 15:40:02 INFO - PID 85347 | ************************* 15:40:02 INFO - PID 85347 | A coding exception was thrown and uncaught in a Task. 15:40:02 INFO - PID 85347 | 15:40:02 ERROR - PID 85347 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:40:02 INFO - PID 85347 | Full stack: 15:40:02 INFO - PID 85347 | ************************* 15:41:22 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-ext.html (next: http://localhost:61287/tests/dromaeo/cssquery-jquery.html) 15:41:23 INFO - PID 85347 | RSS: Main: 367538176 15:41:23 INFO - PID 85347 | 15:44:25 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61287/tests/dromaeo/cssquery-mootools.html) 15:44:26 INFO - PID 85347 | RSS: Main: 342134784 15:44:26 INFO - PID 85347 | 15:47:28 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61287/tests/dromaeo/cssquery-prototype.html) 15:47:29 INFO - PID 85347 | RSS: Main: 361263104 15:47:29 INFO - PID 85347 | 15:50:33 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61287/tests/dromaeo/cssquery-yui.html) 15:50:34 INFO - PID 85347 | RSS: Main: 332480512 15:50:34 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | Cycle 1(1): loaded http://localhost:61287/tests/dromaeo/cssquery-yui.html (next: http://localhost:61287/tests/dromaeo/cssquery-dojo.html) 15:53:38 INFO - PID 85347 | RSS: Main: 371871744 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | __start_tp_report 15:53:38 INFO - PID 85347 | _x_x_mozilla_page_load 15:53:38 INFO - PID 85347 | _x_x_mozilla_page_load_details 15:53:38 INFO - PID 85347 | |i|pagename|runs| 15:53:38 INFO - PID 85347 | |0;dojo.html;2675.371287747379;3484.268303656232;3509.596396414412;3512.490688850118;3527.594326652434;6019.277686677594;6137.416945390192;6198.194234749488;6244.750209991601;6247.968760156198;6110.558759196664;6137.72380242889;6155.8768824623485;6164.722587483563;6178.443940045407;5090.414602320743;5411.621186516945;5596.188552659862;5601.215829783833;5638.097904335307;5650.0959846424375;5717.885642287151;5722.856928576777;5730.828075157752;5760.74076666551;5447.972760136174;5516.227728118067;5535.114381698949;5549.361823390322;5630.943690563083;5660.179274005246;5664.348599911022;5676.233708449347;5700.686462244616;5714.285714285715;5549.528290095347;5550.69471179085;5577.3028371453565;5581.441855814427;5595.524380427669;6190.752369905198;6199.163112979781;6202.751889924398;6202.937970620281;6210.192674952273;6359.936400635981;6404.903926441107;6427.807165785034;6428.067930150149;6428.485721142298;6461.83845403864;6500.544961852672;6505.512086593524;6532.6407047612365;6664.233613134455;6159.846003849895;6223.315435302113;6237.282712488122;6242.87514249717;6252.968735156295;6833.145856767904;6839.726410943506;6853.348931851466;6857.451403887677;6871.312868713088;6238.563300568917;6316.05259211122;6338.873222535523;6482.837929051811;6507.9023814642815;6568.080468734381;6615.073889655452;6655;6662.367075127952;6669.766558170538;6542.149520562391;6557.672116394161;6571.112899758567;6574.737010519621;6576.80269591913;6318.988961766096;6592.30780768022;6609.405153536205;6611.371919667624;6616.27221005689;5270.7628156733035;5289.629725919226;5307.6284660073425;5340.145576707708;5347.732613369394;4663.930041049387;4689.413823272091;4697.248440249544;4701.529847015271;4723.929141062818;6177.938220617736;6204.441600255998;6230.065490176419;6245.75016999324;6260.624362538262;6429.903551446732;6505.056766768792;6523.445507131947;6563.967180164069;6702.0282059101155;5310.628256022119;6202.596831205957;6235.532335074888;6237.812865614039;6257.2804127525915;5941.405859414024;5946.375630558816;5948.583599148104;5953.493953014043;5966.612170208922;6564.8358791030605;6665.400113989765;6756.932430675631;6853.691583878837;6872.278410766898;5968.552358573038;6507.414332710079;6517.576357536745;6650.035744817072;6666.133402657623;6819.386255237053;6823.454123670193;6830.965845170941;6844.041834143124;6858.5199036067;6419.325970773001;6533.41199292066;6568.6058836469965;6583.934160658524;6585.242697089896;5329.7068661224;6420.6147631142285;6491.7403303869205;6582.3746744059235;6628.171478565179;6421.8715625688155;6440.25937017249;6446.065320528403;6453.677316134081;6456.257530384066;6284.465820405317;6285.434310911857;6299.590526615755;6333.493320534439;6373.776917807854;6299;6301.40136687014;6332.588381755171;6333.683315834282;6337.302896681269;6464.741410343534;6467.3209313023335;6475.870482590228;6508.772192973223;6508.8047358579315;6207.410296021871;6296.968515157395;6333.20834895638;6353.904691429725;6364.87270254583 15:53:38 INFO - PID 85347 | |1;ext.html;745.9254074592542;801.4189712458467;803.6142651527267;1024.7847951930094;1132.6998345438456;27144.728552714467;27704.72295277049;27728.44543109139;35296;35935;37032.62967370332;37547.624523754756;38238.808805956;38358.61641383585;38686.22627547447;8284.710035148772;8450.112738162497;8454.830903381928;8455.112213217615;8466.32269418448;6030.125631783389;6171.59884607499;8230.300424463927;8462;8584.31325493959;7889.842203155933;8016.318612917909;8471.83056338876;8496.957515212416;8509.95745021274;8457.45026573274;8559.443636163629;8569.143085691443;8588.484690918502;8614.741557753277;5979.820605381846;6119.632822030692;6144.41628045335;8359.456635318686;8459.280961118313;26171;26544.336391590172;34326.79856205021;34774;34854.9543513695;2975.4049190162054;2987.760979121665;3022.9395412091853;3030.6060212172497;3124.1877111951057;26294.81673324705;26436.206913792616;26500.867495662398;26837.86581067082;27511.58732619012;2692.6634170728657;2705.256054584985;2715.4433341165013;2749.6425464689664;2758.655168103987;321745.9999999977;321762;322006;322020;322418;49149.75425122923;49224.015519689405;49235.75382123067;49242.75378623156;49244.753776231606;51134.74432627813;51198;51277.74361128245;51305;51426.48573514217;13903.930480347533;14063.718725625635;14076.2961851907;14080.014398991972;14090.365933533016;11467.31196128235;11497.942510287396;11499.907508786819;11513.769724605461;11544.711382215512;10163.237757168243;10263.794724105475;10331.793364132825;11941.761164776655;12063.819042714365;5120.359955005624;5297.973510132425;5298.761555730001;5325;5337.62636615441;911.9512560555282;962.1869520255373;988.3773222869546;991.2912267728609;1016.537475448669;21662.458438538848;23336.766632333798;24433.511329773304;24485.63271550928;24508;1025.5128813813378;1081.9621313254156;1094.3105843318658;1096.71485413793;1131.5700033987032;11749.29504229749;12497.87502124967;12513.186642868184;12567.120301578985;12667.93666031664;416338;417750;418411;420679;422487;55272.447275527535;55342.44657553454;55373.52457418197;55474.72262638661;55662;1472.9705405891823;1487.7991471151256;1547.2959803289252;1550.9224538773242;1553.8446155384372;23581.056757730184;23697.407564810328;23709.762902370756;23722.52554948926;23846.403839903447;38980.415293771184;38996.025099372746;39008.60991390163;39120.21759564736;39142.82571522745;419614.80385195755;423421;423925;424855;426621;32317.35365292634;33326;33452.498212527316;33460.83269583636;33478.83260583682;39175.21649566936;42530;43572.56427435685;43712.56287437085;44100.77949610232;31692.841535792173;32601.67398326081;32984.18586977739;33626;33818;32965.50551741678;33091.33817323688;34121.293935303634;34286.82856585701;34371.4844277341;33236.66763332432;33891.66108338885;34303;34402.655973440946;34440.31119377548;24566;27120.72879271182;28500.714992849807;29594.55608165836;30826.537601936438;22970.19604313841;23164.536709265383;23827.04691812308;23867.761322386556;23932.44439111452 15:53:38 INFO - PID 85347 | |2;jquery.html;344.4695169439064;418.2129872087108;432.314781072001;432.7208950227103;439.8130794412376;13086.345682715873;15032.77450838237;21082.472938176554;21150;21417.785822141774;21996.340109796733;22342.994565244564;22364.664530032023;22680.979355928983;22874.77125228747;6053.606515576474;6154.815355539341;6372.490200783949;6383.968080159592;6430.131932189165;5996.610220335664;6167.629942203482;6173.32093469718;6306.274778400474;6309.905351419732;6161.229846269216;6172.22847144107;6188.16459777931;6200.441960223579;6214.875702485948;5856.560757943129;6066.150738896558;6155.846103847395;6170.321264660884;6200.8449788755415;5438.15708565173;5451.400345961941;5452.40023597404;5454.781808727646;5462.317210348707;26367.945282188688;26470.73529264702;26774.732252677615;27031.729682703117;27532;1994.840412766975;2045.1308194017483;2049.702793094993;2057.7839326870762;2133.0721136305756;15552.766708499268;15820.76268855968;15842.28709708066;16926.49220523374;16980.981141131448;1608.702390057843;1616.5473667373155;1626.8535831775198;1649.2166221044909;1700.8384203500648;122739.38630306792;142444;150733;154368.68446973304;154802;10746.462676866126;10817.56729730802;11881.465334059989;12076.939615302043;12091.214071085351;16277.186140693155;16294.592635183979;16301.184940752915;16302.347906083623;16303.59241018984;8627.870581941275;8881.600327985256;8898.154675305836;8921.01868794432;9028.909710902939;8465.661373545112;8493.10822363643;8506.149385061444;8672.696455624024;8686.52224127679;6966.721331146799;7399.445041621792;7624.351930086005;7708.190639982721;7720.652570634335;2059.168772257904;2075.6990236415636;2080.635888719468;2083.9791602084088;2267.161150374372;875.4309698695771;885.017630430222;893.1425831201989;929.6839074714494;940.7130825098411;15292.158931258666;16213.702903767671;16585.49675982968;18174.727379089323;20761.89619051895;752.9247075292536;931.8602209668604;934.8644446555212;949.8242825077383;955.9665411710557;9946.303758736818;10413.375197488174;10423.94788026055;10436.686899393031;10447.955204479491;648005;650257;654100;657462;671691;106194.46902765591;129821;131757.34121329332;134410.32794835963;135416.32291838477;1073.4418102586612;1093.4860615510697;1129.0572372069414;1131.1911982932243;1133.280366966982;17328.740068898725;17366.39217627377;17409.303627854744;17595.472135835946;17853.928764274184;58409.4159058404;59187;59247;59287.70356148192;60141.69929150326;66756;77893;80999.19000809916;81062.59468702618;81962.90185490582;7043.260457652078;8130.268275855202;8650;8777.078406767452;8907.287417006753;13790.793138102736;13938.442462301395;14161.433542658178;14417.78373324422;14518.128912265298;9320.487373194539;9729.491928751055;11404.372759498307;11591.304521728724;12049.81925271104;9168.954155229181;11402.828957565478;11595.246308989888;12022.63932082039;12031.278123312279;10910.727231819268;11934.343611101473;11953.820692689791;12197.207181533173;12337.629871103882;8771.80704823794;8926.86609700868;8960.68637597681;9025.81948361016;9070.229030532479;11590.130740194687;11630.94184529072;12036.097292702907;12065.758684826429;12088.032957363213 15:53:38 INFO - PID 85347 | |3;mootools.html;1186.483879512412;1404.6699025728958;1424.829020517538;1425.1235490173547;1428.1573871415865;10371.585136594536;10776.46117694116;10784.406857622827;10798.190135739831;10807.99999999999;10512.106470949979;10557.313774604645;10858.34849909006;10867.130629549636;10872.510737016833;2364.7162340519117;2405.927822165338;2407.1334319644916;2411.3850968003135;2412.4692567635093;2325.6046472099697;2443.6945381827272;2454.2391858523824;2456.91400800972;2461.396957745346;2056.3008577083788;2069.4722845674405;2143.699882016519;2149.9677504837364;2237.1163390460756;2415.9516809663796;2463.7289898111194;2467.222824810179;2469.901203951849;2472.9505409891703;2395.1207939583264;2409.951800963971;2451.4116612013167;2454.779069883719;2459.2376363327426;10529.947350263199;10657.253992220547;10670.51982660782;10814.837777433344;10822.67531974042;6131.785387511416;6370.649614271213;6381.7766378177;6389.233292004943;6390.009548519987;3791.300841497917;3799.905002374935;4001.6998725095737;4065.10567675111;4067.9423349928934;1144.3019757947645;1165.5862168930078;1190.8511436070492;1200.253630966941;1224.7795396828567;14719;14856.62858428548;15038.248087595686;15045.473408430762;15053.096814191184;5253.106971814801;5572.108462645957;5628.268325117709;5634.267545219095;5634.464725851038;12687.175333603287;12691.682707932376;12716.618501444971;12717.936410317889;12718.364081795873;6372.4583410409705;6372.872542549216;6377;6382.712777925005;6384.233891932998;5560.638558493685;5711.114777209539;5804.564657650693;5809.970950145307;5844.532437404998;4270.957290427118;4401.943533551988;4430.579094985971;4439.977800110979;4468.218061839165;1144.66804626659;1411.4283715095403;1412.1879919046385;1428.1645237536159;1436.2029073864105;707.7770502291762;720.4128635162326;740.326303064209;746.9178390377054;778.6534991928651;5522.834314970557;5905.966455870205;6491.318411566812;6649.767258145942;6739.865202695919;753.7248904149945;854.1586537260794;869.7869022089628;885.1590988560893;901.3730216958322;3891.552471465761;4043.474348334698;4050.743396713019;4088.2436749201493;4175.64507016907;13208.207507549578;13695.726085478234;13697.452101916011;13736.175829450265;13761.931190343985;13613.523526676718;13615.591532254048;13617;13620.93189534046;13648.590542283748;9115.042920493386;9949.502524873727;9970.45162516054;10160.187185025181;10200.183985281159;6154.107654389999;6226.097215903578;6309.747610095545;6321.589096708699;6412.134361861089;12063.939680301542;12437.315947622968;12463.439145238304;12472.625821225378;12476.937615312228;10512.57949682004;11454.599089031844;11631.88368116342;11802.645920622395;12601.487821461484;7466.925330746841;8336.833263334578;8348.415610907177;8351.457155284887;8364.330853531581;9926.751831204278;9928.503574821374;9954.153896918842;9971.900280997388;9972.950135249568;8679.566021699016;8841.557922103997;8891;9008.189262966365;9073.818523629623;8421.621027053674;8518.914810851813;8546.188112129337;8651.653933842575;8658.39391242607;7308.96345518269;7338.156112047183;7353.448491363062;7438.033055702724;7441.479096463196;5479.0959491683425;5534.529564987021;5565.55475561946;5600.187972744011;5813.128030795415;4868.9756551217015;5691.4877661010705;5811.608276686371;6064.514838812796;6083.361247069171 15:53:38 INFO - PID 85347 | |4;prototype.html;352.3129896701431;357.98031108289035;394.046407693382;404.17346526353606;405.7606012452652;5890;6048.697565121737;6081.331053584102;6205.348438413969;6264.248290205171;4294.935575966363;5752.7986520471795;5922.845045216184;5937.3391887368425;6086.604370715901;4128.605408862867;4671.878749100226;4770.594499467541;4802.759862006903;4808.629540580935;4557.977210113945;4681.625469962411;4734.218853889104;4789.640776941726;4804.615630749532;4002.577152246769;4775.4985726498735;4776.737279449629;4795.688280261789;4803.823063349484;4234.089670720795;4742.241241401359;4779.952200477986;4782.450018247911;4800.519948005206;3878.2437424702193;3895.8831235062994;4212.136512015029;4247.384129301265;4265.808038638238;5351.785928562853;5504.80733174341;5935.49548288396;5940.584159108864;5990.8502287442725;4813;5485.396606373296;5501.449855014506;5507.476789704971;5516.531094856942;4698.788554515055;6347.936520634781;6390;6440.613563186177;6611.636360000198;3739.7756134631736;4482.193205223059;4682.180618391768;4692.36653051837;4711.623070154379;6573;6588.835279118012;6752.5948443093575;6893.0005149253575;6897.172339319263;3704.4258139988347;4402.449693788277;4462.732236065846;4467.687261891701;4469.396631454777;4251.87244382669;4400.207962566768;4551.226291530382;4553;4553.772311384418;4606.401167848158;5120.564751996122;5162.92255616166;5174.896502069938;5333.666583354161;5143.922841157386;5831.950248955229;6474.417302442803;6506.958886578125;6537.280899101095;1442.3365251984205;1473.5432016075051;1526.5267766992047;1551.1313664348002;1553.0448774003905;878.4246318661287;894.2398960883327;1001.5077534473727;1025.8820235672845;1028.5268776362807;5121.129408000649;5406.324209473816;6049.245718741516;6114.296855861544;6125.571210015345;6091.14723938649;6240.251169859646;6714.959181326903;6729.596224226562;6749.751296010254;5076.416212135578;5316.813911513079;5724.107606827314;6090.634561926298;6143.232095988002;2673.639058727047;3136.090533745234;3489.7196635162863;3521.4189658706027;3522.330757156132;6574.145361103026;6939.653017349112;6943.51395402317;7001;7063.328983746535;7021.5787052777;7055;7081.575105493687;7095.062240456493;7116.217216106224;6751.56114852533;6943.166819981635;7019.78748807299;7026.859462810715;7037.542559733499;5531.39589519035;6444.581102228341;6805.387515123464;6851.48613853953;6882.655867206519;6121.755129794937;6319.46284565817;6554.279029306677;6667.933320666732;6776.728930842711;5503.669779813224;6964.79105626832;7004.509684322048;7062.823429414306;7064.505484616027;3761.7931013794505;4442.445144199504;4461.308497182942;4477.910441791081;4483.417155769859;5629.54963602919;6600.636964966973;6644.803935291694;6690.565113267622;6713.395794378529;6516.478681705357;6518.152640156749;6533.803985880431;6548.8035358939305;6555.508336874658;6273.870703273455;6487.156669632917;6492.513061520499;6513.446357059703;6540;5785.334686511105;6390.64851433157;6424;6427.517936154901;6460.5463770653105;5708.65748055118;6208.751649934133;6425.361532809104;6449.1301370889505;6559.147310849559;4819.445763737215;5495.422980587141;6269;6385.616862988236;6428.453581445442 15:53:38 INFO - PID 85347 | |5;yui.html;721.902543156674;722.6820199112393;730.3208016544613;734.6473692627541;766.6090293950084;14893.329800158992;15633.218339083058;15680.059196448206;17490.82509174908;18381.90809045956;16866.24101915414;17074.402395916113;17074.804763666547;17356.392526261585;17656.558586035357;958.7315551645551;977.8533220016983;988.4168340678998;988.7330420786384;991.2862738828032;943.5612440215299;973.0852998181722;973.3624475968217;983.0218932162472;986.4722373530162;1034.6223628375626;1036.0106098675747;1040.209440824971;1040.5369610523321;1046.3042077018774;1028.9948550257238;1033.204432586904;1033.245730616654;1036.4144258493927;1036.9377837329785;766.264386189259;766.9884951725728;767.1829501580798;770.6493545436813;772.2393442459173;15676.843231567653;15986.44047458345;16157.676846463122;16245.593860153473;16540.83459165405;14507.274636268145;14743.705125897422;14851.554453366416;14945.70108597822;15671.68656626861;11123.833142502868;11342.262752921033;11704.590339338083;11825.640051394113;12199.573014944433;2967.8901134956777;3100.3334283129234;3129.9217519561967;3169.259576502447;3172.397244523533;48496.67875626536;80904.19095809026;103586.96413035924;103742.73800833945;107230.46384768104;11628.244164129474;11670.649880503599;11760.35318057515;11807.409629518661;12077.094217933514;24460.38849028753;24479.877600611882;24485.877570612032;24500.387490312885;24518.264452066465;7964.2433968772875;8007.799805004922;8088.676452941816;8205.056418511947;8320.246496372081;4041.373587094005;4132.855350062733;4181.853635122756;4197.24449599069;4208.494980602347;4182.1008483176265;4250.213710463574;4265.893352666209;4282.293421585466;4299.849505267363;848.6605357856783;852.6887685994567;859.5916939453834;861.1388611388611;880.55972013993;458.317107509808;573.8651416917021;577.3937365765929;579.3279795437272;589.3340525206489;9848.50757462124;9985.650502232387;10285.588576456857;10391.740206494747;10442.269041167197;552.6711606594071;574.2420005592578;590.3476658292525;605.8455093951014;612.1796792298342;4698.694584851974;4790.592799612003;4793.448753393404;4803.313041617102;4842.58837998767;94586;98412;98664.01335986692;99933;102195;66829.33170668231;78379;79719.60140199262;80695.59652201702;83749.58125209456;52256.73871630617;68608;75349;75395.62302188454;76206;9688.85466718014;10190.337628054152;10903.346058388865;10915.345079295268;11056.889431105586;37138.44292335563;37297.81351093227;37813.62186378101;38192;38443.42334864923;48483;49183;49216.75391623019;49528.00943981172;50043.49956500534;13376.866231337952;13506.054576179966;13660.316984150952;13849;13955.511557095453;14394.35225414838;14878.330475128862;14962;15001.549953501413;15046.699066018837;13292.01239628127;13550.325137827434;13554.525591604246;13703.246321452416;13753.174809511462;13615;13625.114367566077;13631.38658760378;13785.931070344584;14463.987520873437;12779.297138657092;13241.404136814059;13262.336883155996;13370.799438008633;13522;11022.614208502664;11149.554017839195;11167;11796.03767456946;12159.628159348682;11074.5570177192;11265.605703800327;11295.774084518427;11365.715857103638;11450.885491145315 15:53:38 INFO - PID 85347 | __end_tp_report 15:53:38 INFO - PID 85347 | __start_cc_report 15:53:38 INFO - PID 85347 | _x_x_mozilla_cycle_collect,1794 15:53:38 INFO - PID 85347 | __end_cc_report 15:53:38 INFO - PID 85347 | __startTimestamp1490223218426__endTimestamp 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | ------- Summary: start ------- 15:53:38 INFO - PID 85347 | Number of tests: 6 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#0] dojo.html Cycles:165 Average:6105.99 Median:6299.00 stddev:694.21 (11.0%) stddev-sans-first:642.05 15:53:38 INFO - PID 85347 | Values: 2675.4 3484.3 3509.6 3512.5 3527.6 6019.3 6137.4 6198.2 6244.8 6248.0 6110.6 6137.7 6155.9 6164.7 6178.4 5090.4 5411.6 5596.2 5601.2 5638.1 5650.1 5717.9 5722.9 5730.8 5760.7 5448.0 5516.2 5535.1 5549.4 5630.9 5660.2 5664.3 5676.2 5700.7 5714.3 5549.5 5550.7 5577.3 5581.4 5595.5 6190.8 6199.2 6202.8 6202.9 6210.2 6359.9 6404.9 6427.8 6428.1 6428.5 6461.8 6500.5 6505.5 6532.6 6664.2 6159.8 6223.3 6237.3 6242.9 6253.0 6833.1 6839.7 6853.3 6857.5 6871.3 6238.6 6316.1 6338.9 6482.8 6507.9 6568.1 6615.1 6655.0 6662.4 6669.8 6542.1 6557.7 6571.1 6574.7 6576.8 6319.0 6592.3 6609.4 6611.4 6616.3 5270.8 5289.6 5307.6 5340.1 5347.7 4663.9 4689.4 4697.2 4701.5 4723.9 6177.9 6204.4 6230.1 6245.8 6260.6 6429.9 6505.1 6523.4 6564.0 6702.0 5310.6 6202.6 6235.5 6237.8 6257.3 5941.4 5946.4 5948.6 5953.5 5966.6 6564.8 6665.4 6756.9 6853.7 6872.3 5968.6 6507.4 6517.6 6650.0 6666.1 6819.4 6823.5 6831.0 6844.0 6858.5 6419.3 6533.4 6568.6 6583.9 6585.2 5329.7 6420.6 6491.7 6582.4 6628.2 6421.9 6440.3 6446.1 6453.7 6456.3 6284.5 6285.4 6299.6 6333.5 6373.8 6299.0 6301.4 6332.6 6333.7 6337.3 6464.7 6467.3 6475.9 6508.8 6508.8 6207.4 6297.0 6333.2 6353.9 6364.9 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#1] ext.html Cycles:180 Average:51844.41 Median:33854.83 stddev:103787.49 (306.6%) stddev-sans-first:104007.73 15:53:38 INFO - PID 85347 | Values: 745.9 801.4 803.6 1024.8 1132.7 27144.7 27704.7 27728.4 35296.0 35935.0 37032.6 37547.6 38238.8 38358.6 38686.2 8284.7 8450.1 8454.8 8455.1 8466.3 6030.1 6171.6 8230.3 8462.0 8584.3 7889.8 8016.3 8471.8 8497.0 8510.0 8457.5 8559.4 8569.1 8588.5 8614.7 5979.8 6119.6 6144.4 8359.5 8459.3 26171.0 26544.3 34326.8 34774.0 34855.0 2975.4 2987.8 3022.9 3030.6 3124.2 26294.8 26436.2 26500.9 26837.9 27511.6 2692.7 2705.3 2715.4 2749.6 2758.7 321746.0 321762.0 322006.0 322020.0 322418.0 49149.8 49224.0 49235.8 49242.8 49244.8 51134.7 51198.0 51277.7 51305.0 51426.5 13903.9 14063.7 14076.3 14080.0 14090.4 11467.3 11497.9 11499.9 11513.8 11544.7 10163.2 10263.8 10331.8 11941.8 12063.8 5120.4 5298.0 5298.8 5325.0 5337.6 912.0 962.2 988.4 991.3 1016.5 21662.5 23336.8 24433.5 24485.6 24508.0 1025.5 1082.0 1094.3 1096.7 1131.6 11749.3 12497.9 12513.2 12567.1 12667.9 416338.0 417750.0 418411.0 420679.0 422487.0 55272.4 55342.4 55373.5 55474.7 55662.0 1473.0 1487.8 1547.3 1550.9 1553.8 23581.1 23697.4 23709.8 23722.5 23846.4 38980.4 38996.0 39008.6 39120.2 39142.8 419614.8 423421.0 423925.0 424855.0 426621.0 32317.4 33326.0 33452.5 33460.8 33478.8 39175.2 42530.0 43572.6 43712.6 44100.8 31692.8 32601.7 32984.2 33626.0 33818.0 32965.5 33091.3 34121.3 34286.8 34371.5 33236.7 33891.7 34303.0 34402.7 34440.3 24566.0 27120.7 28500.7 29594.6 30826.5 22970.2 23164.5 23827.0 23867.8 23932.4 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#2] jquery.html Cycles:180 Average:37896.08 Median:24621.36 stddev:109656.96 (445.4%) stddev-sans-first:109928.33 15:53:38 INFO - PID 85347 | Values: 344.5 418.2 432.3 432.7 439.8 13086.3 15032.8 21082.5 21150.0 21417.8 21996.3 22343.0 22364.7 22681.0 22874.8 6053.6 6154.8 6372.5 6384.0 6430.1 5996.6 6167.6 6173.3 6306.3 6309.9 6161.2 6172.2 6188.2 6200.4 6214.9 5856.6 6066.2 6155.8 6170.3 6200.8 5438.2 5451.4 5452.4 5454.8 5462.3 26367.9 26470.7 26774.7 27031.7 27532.0 1994.8 2045.1 2049.7 2057.8 2133.1 15552.8 15820.8 15842.3 16926.5 16981.0 1608.7 1616.5 1626.9 1649.2 1700.8 122739.4 142444.0 150733.0 154368.7 154802.0 10746.5 10817.6 11881.5 12076.9 12091.2 16277.2 16294.6 16301.2 16302.3 16303.6 8627.9 8881.6 8898.2 8921.0 9028.9 8465.7 8493.1 8506.1 8672.7 8686.5 6966.7 7399.4 7624.4 7708.2 7720.7 2059.2 2075.7 2080.6 2084.0 2267.2 875.4 885.0 893.1 929.7 940.7 15292.2 16213.7 16585.5 18174.7 20761.9 752.9 931.9 934.9 949.8 956.0 9946.3 10413.4 10423.9 10436.7 10448.0 648005.0 650257.0 654100.0 657462.0 671691.0 106194.5 129821.0 131757.3 134410.3 135416.3 1073.4 1093.5 1129.1 1131.2 1133.3 17328.7 17366.4 17409.3 17595.5 17853.9 58409.4 59187.0 59247.0 59287.7 60141.7 66756.0 77893.0 80999.2 81062.6 81962.9 7043.3 8130.3 8650.0 8777.1 8907.3 13790.8 13938.4 14161.4 14417.8 14518.1 9320.5 9729.5 11404.4 11591.3 12049.8 9169.0 11402.8 11595.2 12022.6 12031.3 10910.7 11934.3 11953.8 12197.2 12337.6 8771.8 8926.9 8960.7 9025.8 9070.2 11590.1 11630.9 12036.1 12065.8 12088.0 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#3] mootools.html Cycles:180 Average:6707.51 Median:4022.59 stddev:4138.70 (102.9%) stddev-sans-first:4129.51 15:53:38 INFO - PID 85347 | Values: 1186.5 1404.7 1424.8 1425.1 1428.2 10371.6 10776.5 10784.4 10798.2 10808.0 10512.1 10557.3 10858.3 10867.1 10872.5 2364.7 2405.9 2407.1 2411.4 2412.5 2325.6 2443.7 2454.2 2456.9 2461.4 2056.3 2069.5 2143.7 2150.0 2237.1 2416.0 2463.7 2467.2 2469.9 2473.0 2395.1 2410.0 2451.4 2454.8 2459.2 10529.9 10657.3 10670.5 10814.8 10822.7 6131.8 6370.6 6381.8 6389.2 6390.0 3791.3 3799.9 4001.7 4065.1 4067.9 1144.3 1165.6 1190.9 1200.3 1224.8 14719.0 14856.6 15038.2 15045.5 15053.1 5253.1 5572.1 5628.3 5634.3 5634.5 12687.2 12691.7 12716.6 12717.9 12718.4 6372.5 6372.9 6377.0 6382.7 6384.2 5560.6 5711.1 5804.6 5810.0 5844.5 4271.0 4401.9 4430.6 4440.0 4468.2 1144.7 1411.4 1412.2 1428.2 1436.2 707.8 720.4 740.3 746.9 778.7 5522.8 5906.0 6491.3 6649.8 6739.9 753.7 854.2 869.8 885.2 901.4 3891.6 4043.5 4050.7 4088.2 4175.6 13208.2 13695.7 13697.5 13736.2 13761.9 13613.5 13615.6 13617.0 13620.9 13648.6 9115.0 9949.5 9970.5 10160.2 10200.2 6154.1 6226.1 6309.7 6321.6 6412.1 12063.9 12437.3 12463.4 12472.6 12476.9 10512.6 11454.6 11631.9 11802.6 12601.5 7466.9 8336.8 8348.4 8351.5 8364.3 9926.8 9928.5 9954.2 9971.9 9973.0 8679.6 8841.6 8891.0 9008.2 9073.8 8421.6 8518.9 8546.2 8651.7 8658.4 7309.0 7338.2 7353.4 7438.0 7441.5 5479.1 5534.5 5565.6 5600.2 5813.1 4869.0 5691.5 5811.6 6064.5 6083.4 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#4] prototype.html Cycles:180 Average:5270.34 Median:5808.64 stddev:1653.36 (28.5%) stddev-sans-first:1616.26 15:53:38 INFO - PID 85347 | Values: 352.3 358.0 394.0 404.2 405.8 5890.0 6048.7 6081.3 6205.3 6264.2 4294.9 5752.8 5922.8 5937.3 6086.6 4128.6 4671.9 4770.6 4802.8 4808.6 4558.0 4681.6 4734.2 4789.6 4804.6 4002.6 4775.5 4776.7 4795.7 4803.8 4234.1 4742.2 4780.0 4782.5 4800.5 3878.2 3895.9 4212.1 4247.4 4265.8 5351.8 5504.8 5935.5 5940.6 5990.9 4813.0 5485.4 5501.4 5507.5 5516.5 4698.8 6347.9 6390.0 6440.6 6611.6 3739.8 4482.2 4682.2 4692.4 4711.6 6573.0 6588.8 6752.6 6893.0 6897.2 3704.4 4402.4 4462.7 4467.7 4469.4 4251.9 4400.2 4551.2 4553.0 4553.8 4606.4 5120.6 5162.9 5174.9 5333.7 5143.9 5832.0 6474.4 6507.0 6537.3 1442.3 1473.5 1526.5 1551.1 1553.0 878.4 894.2 1001.5 1025.9 1028.5 5121.1 5406.3 6049.2 6114.3 6125.6 6091.1 6240.3 6715.0 6729.6 6749.8 5076.4 5316.8 5724.1 6090.6 6143.2 2673.6 3136.1 3489.7 3521.4 3522.3 6574.1 6939.7 6943.5 7001.0 7063.3 7021.6 7055.0 7081.6 7095.1 7116.2 6751.6 6943.2 7019.8 7026.9 7037.5 5531.4 6444.6 6805.4 6851.5 6882.7 6121.8 6319.5 6554.3 6667.9 6776.7 5503.7 6964.8 7004.5 7062.8 7064.5 3761.8 4442.4 4461.3 4477.9 4483.4 5629.5 6600.6 6644.8 6690.6 6713.4 6516.5 6518.2 6533.8 6548.8 6555.5 6273.9 6487.2 6492.5 6513.4 6540.0 5785.3 6390.6 6424.0 6427.5 6460.5 5708.7 6208.8 6425.4 6449.1 6559.1 4819.4 5495.4 6269.0 6385.6 6428.5 15:53:38 INFO - PID 85347 | 15:53:38 INFO - PID 85347 | [#5] yui.html Cycles:180 Average:18868.33 Median:13743.08 stddev:25727.16 (187.2%) stddev-sans-first:25763.24 15:53:38 INFO - PID 85347 | Values: 721.9 722.7 730.3 734.6 766.6 14893.3 15633.2 15680.1 17490.8 18381.9 16866.2 17074.4 17074.8 17356.4 17656.6 958.7 977.9 988.4 988.7 991.3 943.6 973.1 973.4 983.0 986.5 1034.6 1036.0 1040.2 1040.5 1046.3 1029.0 1033.2 1033.2 1036.4 1036.9 766.3 767.0 767.2 770.6 772.2 15676.8 15986.4 16157.7 16245.6 16540.8 14507.3 14743.7 14851.6 14945.7 15671.7 11123.8 11342.3 11704.6 11825.6 12199.6 2967.9 3100.3 3129.9 3169.3 3172.4 48496.7 80904.2 103587.0 103742.7 107230.5 11628.2 11670.6 11760.4 11807.4 12077.1 24460.4 24479.9 24485.9 24500.4 24518.3 7964.2 8007.8 8088.7 8205.1 8320.2 4041.4 4132.9 4181.9 4197.2 4208.5 4182.1 4250.2 4265.9 4282.3 4299.8 848.7 852.7 859.6 861.1 880.6 458.3 573.9 577.4 579.3 589.3 9848.5 9985.7 10285.6 10391.7 10442.3 552.7 574.2 590.3 605.8 612.2 4698.7 4790.6 4793.4 4803.3 4842.6 94586.0 98412.0 98664.0 99933.0 102195.0 66829.3 78379.0 79719.6 80695.6 83749.6 52256.7 68608.0 75349.0 75395.6 76206.0 9688.9 10190.3 10903.3 10915.3 11056.9 37138.4 37297.8 37813.6 38192.0 38443.4 48483.0 49183.0 49216.8 49528.0 50043.5 13376.9 13506.1 13660.3 13849.0 13955.5 14394.4 14878.3 14962.0 15001.5 15046.7 13292.0 13550.3 13554.5 13703.2 13753.2 13615.0 13625.1 13631.4 13785.9 14464.0 12779.3 13241.4 13262.3 13370.8 13522.0 11022.6 11149.6 11167.0 11796.0 12159.6 11074.6 11265.6 11295.8 11365.7 11450.9 15:53:38 INFO - PID 85347 | -------- Summary: end -------- 15:53:38 INFO - PID 85347 | 15:53:39 INFO - TEST-INFO | 85347: exit 0 15:53:39 INFO - TEST-OK | dromaeo_css | took 1122000ms 15:53:39 INFO - TEST-START | kraken 15:53:39 INFO - operating with platform_type : mac_ 15:53:39 INFO - Initialising browser for kraken test... 15:53:39 INFO - TEST-INFO | started process 85349 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpSzpX_X/profile http://localhost:61287/getInfo.html) 15:53:39 INFO - PID 85349 | 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "TopSites": { 15:53:39 INFO - PID 85349 | "rows": [ 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "Facebook", 15:53:39 INFO - PID 85349 | "url": "https://www.facebook.com/" 15:53:39 INFO - PID 85349 | }, 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "YouTube", 15:53:39 INFO - PID 85349 | "url": "https://www.youtube.com/" 15:53:39 INFO - PID 85349 | }, 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "Amazon", 15:53:39 INFO - PID 85349 | "url": "http://www.amazon.com/" 15:53:39 INFO - PID 85349 | }, 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "Yahoo", 15:53:39 INFO - PID 85349 | "url": "https://www.yahoo.com/" 15:53:39 INFO - PID 85349 | }, 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "eBay", 15:53:39 INFO - PID 85349 | "url": "http://www.ebay.com" 15:53:39 INFO - PID 85349 | }, 15:53:39 INFO - PID 85349 | { 15:53:39 INFO - PID 85349 | "title": "Twitter", 15:53:39 INFO - PID 85349 | "url": "https://twitter.com/" 15:53:39 INFO - PID 85349 | } 15:53:39 INFO - PID 85349 | ] 15:53:39 INFO - PID 85349 | } 15:53:39 INFO - PID 85349 | } 15:53:41 INFO - PID 85349 | __metrics Screen width/height:1600/1200 15:53:41 INFO - PID 85349 | colorDepth:24 15:53:41 INFO - PID 85349 | Browser inner width/height: 1024/689 15:53:41 INFO - PID 85349 | __metrics 15:53:41 INFO - PID 85349 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:53:41 INFO - PID 85349 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:53:42 INFO - TEST-INFO | 85349: exit 0 15:53:42 INFO - Browser initialized. 15:53:42 INFO - Running cycle 1/1 for kraken test... 15:53:42 INFO - TEST-INFO | started process 85350 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpSzpX_X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:42 INFO - PID 85350 | 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "TopSites": { 15:53:42 INFO - PID 85350 | "rows": [ 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "Facebook", 15:53:42 INFO - PID 85350 | "url": "https://www.facebook.com/" 15:53:42 INFO - PID 85350 | }, 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "YouTube", 15:53:42 INFO - PID 85350 | "url": "https://www.youtube.com/" 15:53:42 INFO - PID 85350 | }, 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "Amazon", 15:53:42 INFO - PID 85350 | "url": "http://www.amazon.com/" 15:53:42 INFO - PID 85350 | }, 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "Yahoo", 15:53:42 INFO - PID 85350 | "url": "https://www.yahoo.com/" 15:53:42 INFO - PID 85350 | }, 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "eBay", 15:53:42 INFO - PID 85350 | "url": "http://www.ebay.com" 15:53:42 INFO - PID 85350 | }, 15:53:42 INFO - PID 85350 | { 15:53:42 INFO - PID 85350 | "title": "Twitter", 15:53:42 INFO - PID 85350 | "url": "https://twitter.com/" 15:53:42 INFO - PID 85350 | } 15:53:42 INFO - PID 85350 | ] 15:53:42 INFO - PID 85350 | } 15:53:42 INFO - PID 85350 | } 15:53:54 INFO - PID 85350 | RSS: Main: 234278912 15:53:54 INFO - PID 85350 | 15:53:57 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61287/tests/kraken/driver.html?testName=audio-beat-detection) 15:53:58 INFO - PID 85350 | RSS: Main: 284966912 15:53:58 INFO - PID 85350 | 15:54:01 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61287/tests/kraken/driver.html?testName=audio-dft) 15:54:01 INFO - PID 85350 | RSS: Main: 287600640 15:54:01 INFO - PID 85350 | 15:54:05 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61287/tests/kraken/driver.html?testName=audio-fft) 15:54:05 INFO - PID 85350 | RSS: Main: 379322368 15:54:05 INFO - PID 85350 | 15:54:07 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61287/tests/kraken/driver.html?testName=audio-oscillator) 15:54:08 INFO - PID 85350 | RSS: Main: 280895488 15:54:08 INFO - PID 85350 | 15:54:10 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61287/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:54:11 INFO - PID 85350 | RSS: Main: 284299264 15:54:11 INFO - PID 85350 | 15:54:14 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61287/tests/kraken/driver.html?testName=imaging-darkroom) 15:54:14 INFO - PID 85350 | RSS: Main: 325873664 15:54:14 INFO - PID 85350 | 15:54:17 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61287/tests/kraken/driver.html?testName=imaging-desaturate) 15:54:18 INFO - PID 85350 | RSS: Main: 342872064 15:54:18 INFO - PID 85350 | 15:54:21 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61287/tests/kraken/driver.html?testName=json-parse-financial) 15:54:21 INFO - PID 85350 | RSS: Main: 356704256 15:54:21 INFO - PID 85350 | 15:54:23 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61287/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:54:24 INFO - PID 85350 | RSS: Main: 305299456 15:54:24 INFO - PID 85350 | 15:54:26 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:54:26 INFO - PID 85350 | RSS: Main: 308682752 15:54:26 INFO - PID 85350 | 15:54:28 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:54:29 INFO - PID 85350 | RSS: Main: 309891072 15:54:29 INFO - PID 85350 | 15:54:32 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:54:33 INFO - PID 85350 | RSS: Main: 323948544 15:54:33 INFO - PID 85350 | 15:54:36 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:54:37 INFO - PID 85350 | RSS: Main: 317816832 15:54:37 INFO - PID 85350 | 15:54:38 INFO - PID 85350 | Cycle 1(1): loaded http://localhost:61287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61287/tests/kraken/driver.html?testName=ai-astar) 15:54:39 INFO - PID 85350 | RSS: Main: 379596800 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | __start_tp_report 15:54:39 INFO - PID 85350 | _x_x_mozilla_page_load 15:54:39 INFO - PID 85350 | _x_x_mozilla_page_load_details 15:54:39 INFO - PID 85350 | |i|pagename|runs| 15:54:39 INFO - PID 85350 | |0;ai-astar;111;111;111;111;112;112;111;112;113;112 15:54:39 INFO - PID 85350 | |1;audio-beat-detection;153;114;110;157;143;145;141;105;173;145 15:54:39 INFO - PID 85350 | |2;audio-dft;185;183;189;186;186;194;257;185;185;187 15:54:39 INFO - PID 85350 | |3;audio-fft;94;63;60;92;57;58;61;58;76;60 15:54:39 INFO - PID 85350 | |4;audio-oscillator;91;71;88;69;70;68;81;66;69;85 15:54:39 INFO - PID 85350 | |5;imaging-gaussian-blur;121;136;89;90;90;89;90;90;89;89 15:54:39 INFO - PID 85350 | |6;imaging-darkroom;95;95;96;95;95;96;94;95;94;95 15:54:39 INFO - PID 85350 | |7;imaging-desaturate;86;84;85;86;86;100;83;86;87;86 15:54:39 INFO - PID 85350 | |8;json-parse-financial;61;61;61;61;62;62;64;63;61;62 15:54:39 INFO - PID 85350 | |9;json-stringify-tinderbox;54;57;61;64;63;60;60;62;60;61 15:54:39 INFO - PID 85350 | |10;stanford-crypto-aes;84;73;95;97;111;106;84;83;82;85 15:54:39 INFO - PID 85350 | |11;stanford-crypto-ccm;144;138;143;139;147;139;160;148;179;148 15:54:39 INFO - PID 85350 | |12;stanford-crypto-pbkdf2;187;187;233;187;186;186;187;186;184;189 15:54:39 INFO - PID 85350 | |13;stanford-crypto-sha256-iterative;67;67;66;69;67;67;68;66;67;67 15:54:39 INFO - PID 85350 | __end_tp_report 15:54:39 INFO - PID 85350 | __start_cc_report 15:54:39 INFO - PID 85350 | _x_x_mozilla_cycle_collect,2169 15:54:39 INFO - PID 85350 | __end_cc_report 15:54:39 INFO - PID 85350 | __startTimestamp1490223279209__endTimestamp 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | ------- Summary: start ------- 15:54:39 INFO - PID 85350 | Number of tests: 14 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 15:54:39 INFO - PID 85350 | Values: 111.0 111.0 111.0 111.0 112.0 112.0 111.0 112.0 113.0 112.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:145.00 stddev:22.08 (15.2%) stddev-sans-first:22.80 15:54:39 INFO - PID 85350 | Values: 153.0 114.0 110.0 157.0 143.0 145.0 141.0 105.0 173.0 145.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#2] audio-dft Cycles:10 Average:193.70 Median:186.50 stddev:22.45 (12.0%) stddev-sans-first:23.58 15:54:39 INFO - PID 85350 | Values: 185.0 183.0 189.0 186.0 186.0 194.0 257.0 185.0 185.0 187.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#3] audio-fft Cycles:10 Average:67.90 Median:62.00 stddev:14.29 (23.1%) stddev-sans-first:11.63 15:54:39 INFO - PID 85350 | Values: 94.0 63.0 60.0 92.0 57.0 58.0 61.0 58.0 76.0 60.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.00 stddev:9.41 (12.4%) stddev-sans-first:8.22 15:54:39 INFO - PID 85350 | Values: 91.0 71.0 88.0 69.0 70.0 68.0 81.0 66.0 69.0 85.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#5] imaging-gaussian-blur Cycles:10 Average:97.30 Median:89.50 stddev:16.83 (18.8%) stddev-sans-first:15.51 15:54:39 INFO - PID 85350 | Values: 121.0 136.0 89.0 90.0 90.0 89.0 90.0 90.0 89.0 89.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 15:54:39 INFO - PID 85350 | Values: 95.0 95.0 96.0 95.0 95.0 96.0 94.0 95.0 94.0 95.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#7] imaging-desaturate Cycles:10 Average:86.90 Median:86.00 stddev:4.75 (5.5%) stddev-sans-first:5.02 15:54:39 INFO - PID 85350 | Values: 86.0 84.0 85.0 86.0 86.0 100.0 83.0 86.0 87.0 86.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:1.03 (1.7%) stddev-sans-first:1.05 15:54:39 INFO - PID 85350 | Values: 61.0 61.0 61.0 61.0 62.0 62.0 64.0 63.0 61.0 62.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:61.00 stddev:2.90 (4.8%) stddev-sans-first:2.03 15:54:39 INFO - PID 85350 | Values: 54.0 57.0 61.0 64.0 63.0 60.0 60.0 62.0 60.0 61.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:84.00 stddev:11.88 (14.1%) stddev-sans-first:12.40 15:54:39 INFO - PID 85350 | Values: 84.0 73.0 95.0 97.0 111.0 106.0 84.0 83.0 82.0 85.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:147.50 stddev:12.50 (8.5%) stddev-sans-first:13.15 15:54:39 INFO - PID 85350 | Values: 144.0 138.0 143.0 139.0 147.0 139.0 160.0 148.0 179.0 148.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:187.00 stddev:14.74 (7.9%) stddev-sans-first:15.56 15:54:39 INFO - PID 85350 | Values: 187.0 187.0 233.0 187.0 186.0 186.0 187.0 186.0 184.0 189.0 15:54:39 INFO - PID 85350 | 15:54:39 INFO - PID 85350 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 15:54:39 INFO - PID 85350 | Values: 67.0 67.0 66.0 69.0 67.0 67.0 68.0 66.0 67.0 67.0 15:54:39 INFO - PID 85350 | -------- Summary: end -------- 15:54:39 INFO - PID 85350 | 15:54:40 INFO - TEST-INFO | 85350: exit 0 15:54:40 INFO - TEST-OK | kraken | took 60925ms 15:54:40 INFO - SUITE-END | took 1182s 15:54:40 INFO - Completed test suite (00:19:43) 15:54:40 INFO - javascript benchmark 15:54:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7602.616607263094, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2675.371287747379, 3484.268303656232, 3509.596396414412, 3512.490688850118, 3527.594326652434, 6019.277686677594, 6137.416945390192, 6198.194234749488, 6244.750209991601, 6247.968760156198, 6110.558759196664, 6137.72380242889, 6155.8768824623485, 6164.722587483563, 6178.443940045407, 5090.414602320743, 5411.621186516945, 5596.188552659862, 5601.215829783833, 5638.097904335307, 5650.0959846424375, 5717.885642287151, 5722.856928576777, 5730.828075157752, 5760.74076666551, 5447.972760136174, 5516.227728118067, 5535.114381698949, 5549.361823390322, 5630.943690563083, 5660.179274005246, 5664.348599911022, 5676.233708449347, 5700.686462244616, 5714.285714285715, 5549.528290095347, 5550.69471179085, 5577.3028371453565, 5581.441855814427, 5595.524380427669, 6190.752369905198, 6199.163112979781, 6202.751889924398, 6202.937970620281, 6210.192674952273, 6359.936400635981, 6404.903926441107, 6427.807165785034, 6428.067930150149, 6428.485721142298, 6461.83845403864, 6500.544961852672, 6505.512086593524, 6532.6407047612365, 6664.233613134455, 6159.846003849895, 6223.315435302113, 6237.282712488122, 6242.87514249717, 6252.968735156295, 6833.145856767904, 6839.726410943506, 6853.348931851466, 6857.451403887677, 6871.312868713088, 6238.563300568917, 6316.05259211122, 6338.873222535523, 6482.837929051811, 6507.9023814642815, 6568.080468734381, 6615.073889655452, 6655.0, 6662.367075127952, 6669.766558170538, 6542.149520562391, 6557.672116394161, 6571.112899758567, 6574.737010519621, 6576.80269591913, 6318.988961766096, 6592.30780768022, 6609.405153536205, 6611.371919667624, 6616.27221005689, 5270.7628156733035, 5289.629725919226, 5307.6284660073425, 5340.145576707708, 5347.732613369394, 4663.930041049387, 4689.413823272091, 4697.248440249544, 4701.529847015271, 4723.929141062818, 6177.938220617736, 6204.441600255998, 6230.065490176419, 6245.75016999324, 6260.624362538262, 6429.903551446732, 6505.056766768792, 6523.445507131947, 6563.967180164069, 6702.0282059101155, 5310.628256022119, 6202.596831205957, 6235.532335074888, 6237.812865614039, 6257.2804127525915, 5941.405859414024, 5946.375630558816, 5948.583599148104, 5953.493953014043, 5966.612170208922, 6564.8358791030605, 6665.400113989765, 6756.932430675631, 6853.691583878837, 6872.278410766898, 5968.552358573038, 6507.414332710079, 6517.576357536745, 6650.035744817072, 6666.133402657623, 6819.386255237053, 6823.454123670193, 6830.965845170941, 6844.041834143124, 6858.5199036067, 6419.325970773001, 6533.41199292066, 6568.6058836469965, 6583.934160658524, 6585.242697089896, 5329.7068661224, 6420.6147631142285, 6491.7403303869205, 6582.3746744059235, 6628.171478565179, 6421.8715625688155, 6440.25937017249, 6446.065320528403, 6453.677316134081, 6456.257530384066, 6284.465820405317, 6285.434310911857, 6299.590526615755, 6333.493320534439, 6373.776917807854, 6299.0, 6301.40136687014, 6332.588381755171, 6333.683315834282, 6337.302896681269, 6464.741410343534, 6467.3209313023335, 6475.870482590228, 6508.772192973223, 6508.8047358579315, 6207.410296021871, 6296.968515157395, 6333.20834895638, 6353.904691429725, 6364.87270254583], "value": 6058.999554142985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.9254074592542, 801.4189712458467, 803.6142651527267, 1024.7847951930094, 1132.6998345438456, 27144.728552714467, 27704.72295277049, 27728.44543109139, 35296.0, 35935.0, 37032.62967370332, 37547.624523754756, 38238.808805956, 38358.61641383585, 38686.22627547447, 8284.710035148772, 8450.112738162497, 8454.830903381928, 8455.112213217615, 8466.32269418448, 6030.125631783389, 6171.59884607499, 8230.300424463927, 8462.0, 8584.31325493959, 7889.842203155933, 8016.318612917909, 8471.83056338876, 8496.957515212416, 8509.95745021274, 8457.45026573274, 8559.443636163629, 8569.143085691443, 8588.484690918502, 8614.741557753277, 5979.820605381846, 6119.632822030692, 6144.41628045335, 8359.456635318686, 8459.280961118313, 26171.0, 26544.336391590172, 34326.79856205021, 34774.0, 34854.9543513695, 2975.4049190162054, 2987.760979121665, 3022.9395412091853, 3030.6060212172497, 3124.1877111951057, 26294.81673324705, 26436.206913792616, 26500.867495662398, 26837.86581067082, 27511.58732619012, 2692.6634170728657, 2705.256054584985, 2715.4433341165013, 2749.6425464689664, 2758.655168103987, 321745.9999999977, 321762.0, 322006.0, 322020.0, 322418.0, 49149.75425122923, 49224.015519689405, 49235.75382123067, 49242.75378623156, 49244.753776231606, 51134.74432627813, 51198.0, 51277.74361128245, 51305.0, 51426.48573514217, 13903.930480347533, 14063.718725625635, 14076.2961851907, 14080.014398991972, 14090.365933533016, 11467.31196128235, 11497.942510287396, 11499.907508786819, 11513.769724605461, 11544.711382215512, 10163.237757168243, 10263.794724105475, 10331.793364132825, 11941.761164776655, 12063.819042714365, 5120.359955005624, 5297.973510132425, 5298.761555730001, 5325.0, 5337.62636615441, 911.9512560555282, 962.1869520255373, 988.3773222869546, 991.2912267728609, 1016.537475448669, 21662.458438538848, 23336.766632333798, 24433.511329773304, 24485.63271550928, 24508.0, 1025.5128813813378, 1081.9621313254156, 1094.3105843318658, 1096.71485413793, 1131.5700033987032, 11749.29504229749, 12497.87502124967, 12513.186642868184, 12567.120301578985, 12667.93666031664, 416338.0, 417750.0, 418411.0, 420679.0, 422487.0, 55272.447275527535, 55342.44657553454, 55373.52457418197, 55474.72262638661, 55662.0, 1472.9705405891823, 1487.7991471151256, 1547.2959803289252, 1550.9224538773242, 1553.8446155384372, 23581.056757730184, 23697.407564810328, 23709.762902370756, 23722.52554948926, 23846.403839903447, 38980.415293771184, 38996.025099372746, 39008.60991390163, 39120.21759564736, 39142.82571522745, 419614.80385195755, 423421.0, 423925.0, 424855.0, 426621.0, 32317.35365292634, 33326.0, 33452.498212527316, 33460.83269583636, 33478.83260583682, 39175.21649566936, 42530.0, 43572.56427435685, 43712.56287437085, 44100.77949610232, 31692.841535792173, 32601.67398326081, 32984.18586977739, 33626.0, 33818.0, 32965.50551741678, 33091.33817323688, 34121.293935303634, 34286.82856585701, 34371.4844277341, 33236.66763332432, 33891.66108338885, 34303.0, 34402.655973440946, 34440.31119377548, 24566.0, 27120.72879271182, 28500.714992849807, 29594.55608165836, 30826.537601936438, 22970.19604313841, 23164.536709265383, 23827.04691812308, 23867.761322386556, 23932.44439111452], "value": 17140.39985193072, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.4695169439064, 418.2129872087108, 432.314781072001, 432.7208950227103, 439.8130794412376, 13086.345682715873, 15032.77450838237, 21082.472938176554, 21150.0, 21417.785822141774, 21996.340109796733, 22342.994565244564, 22364.664530032023, 22680.979355928983, 22874.77125228747, 6053.606515576474, 6154.815355539341, 6372.490200783949, 6383.968080159592, 6430.131932189165, 5996.610220335664, 6167.629942203482, 6173.32093469718, 6306.274778400474, 6309.905351419732, 6161.229846269216, 6172.22847144107, 6188.16459777931, 6200.441960223579, 6214.875702485948, 5856.560757943129, 6066.150738896558, 6155.846103847395, 6170.321264660884, 6200.8449788755415, 5438.15708565173, 5451.400345961941, 5452.40023597404, 5454.781808727646, 5462.317210348707, 26367.945282188688, 26470.73529264702, 26774.732252677615, 27031.729682703117, 27532.0, 1994.840412766975, 2045.1308194017483, 2049.702793094993, 2057.7839326870762, 2133.0721136305756, 15552.766708499268, 15820.76268855968, 15842.28709708066, 16926.49220523374, 16980.981141131448, 1608.702390057843, 1616.5473667373155, 1626.8535831775198, 1649.2166221044909, 1700.8384203500648, 122739.38630306792, 142444.0, 150733.0, 154368.68446973304, 154802.0, 10746.462676866126, 10817.56729730802, 11881.465334059989, 12076.939615302043, 12091.214071085351, 16277.186140693155, 16294.592635183979, 16301.184940752915, 16302.347906083623, 16303.59241018984, 8627.870581941275, 8881.600327985256, 8898.154675305836, 8921.01868794432, 9028.909710902939, 8465.661373545112, 8493.10822363643, 8506.149385061444, 8672.696455624024, 8686.52224127679, 6966.721331146799, 7399.445041621792, 7624.351930086005, 7708.190639982721, 7720.652570634335, 2059.168772257904, 2075.6990236415636, 2080.635888719468, 2083.9791602084088, 2267.161150374372, 875.4309698695771, 885.017630430222, 893.1425831201989, 929.6839074714494, 940.7130825098411, 15292.158931258666, 16213.702903767671, 16585.49675982968, 18174.727379089323, 20761.89619051895, 752.9247075292536, 931.8602209668604, 934.8644446555212, 949.8242825077383, 955.9665411710557, 9946.303758736818, 10413.375197488174, 10423.94788026055, 10436.686899393031, 10447.955204479491, 648005.0, 650257.0, 654100.0, 657462.0, 671691.0, 106194.46902765591, 129821.0, 131757.34121329332, 134410.32794835963, 135416.32291838477, 1073.4418102586612, 1093.4860615510697, 1129.0572372069414, 1131.1911982932243, 1133.280366966982, 17328.740068898725, 17366.39217627377, 17409.303627854744, 17595.472135835946, 17853.928764274184, 58409.4159058404, 59187.0, 59247.0, 59287.70356148192, 60141.69929150326, 66756.0, 77893.0, 80999.19000809916, 81062.59468702618, 81962.90185490582, 7043.260457652078, 8130.268275855202, 8650.0, 8777.078406767452, 8907.287417006753, 13790.793138102736, 13938.442462301395, 14161.433542658178, 14417.78373324422, 14518.128912265298, 9320.487373194539, 9729.491928751055, 11404.372759498307, 11591.304521728724, 12049.81925271104, 9168.954155229181, 11402.828957565478, 11595.246308989888, 12022.63932082039, 12031.278123312279, 10910.727231819268, 11934.343611101473, 11953.820692689791, 12197.207181533173, 12337.629871103882, 8771.80704823794, 8926.86609700868, 8960.68637597681, 9025.81948361016, 9070.229030532479, 11590.130740194687, 11630.94184529072, 12036.097292702907, 12065.758684826429, 12088.032957363213], "value": 10075.990559974689, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1186.483879512412, 1404.6699025728958, 1424.829020517538, 1425.1235490173547, 1428.1573871415865, 10371.585136594536, 10776.46117694116, 10784.406857622827, 10798.190135739831, 10807.99999999999, 10512.106470949979, 10557.313774604645, 10858.34849909006, 10867.130629549636, 10872.510737016833, 2364.7162340519117, 2405.927822165338, 2407.1334319644916, 2411.3850968003135, 2412.4692567635093, 2325.6046472099697, 2443.6945381827272, 2454.2391858523824, 2456.91400800972, 2461.396957745346, 2056.3008577083788, 2069.4722845674405, 2143.699882016519, 2149.9677504837364, 2237.1163390460756, 2415.9516809663796, 2463.7289898111194, 2467.222824810179, 2469.901203951849, 2472.9505409891703, 2395.1207939583264, 2409.951800963971, 2451.4116612013167, 2454.779069883719, 2459.2376363327426, 10529.947350263199, 10657.253992220547, 10670.51982660782, 10814.837777433344, 10822.67531974042, 6131.785387511416, 6370.649614271213, 6381.7766378177, 6389.233292004943, 6390.009548519987, 3791.300841497917, 3799.905002374935, 4001.6998725095737, 4065.10567675111, 4067.9423349928934, 1144.3019757947645, 1165.5862168930078, 1190.8511436070492, 1200.253630966941, 1224.7795396828567, 14719.0, 14856.62858428548, 15038.248087595686, 15045.473408430762, 15053.096814191184, 5253.106971814801, 5572.108462645957, 5628.268325117709, 5634.267545219095, 5634.464725851038, 12687.175333603287, 12691.682707932376, 12716.618501444971, 12717.936410317889, 12718.364081795873, 6372.4583410409705, 6372.872542549216, 6377.0, 6382.712777925005, 6384.233891932998, 5560.638558493685, 5711.114777209539, 5804.564657650693, 5809.970950145307, 5844.532437404998, 4270.957290427118, 4401.943533551988, 4430.579094985971, 4439.977800110979, 4468.218061839165, 1144.66804626659, 1411.4283715095403, 1412.1879919046385, 1428.1645237536159, 1436.2029073864105, 707.7770502291762, 720.4128635162326, 740.326303064209, 746.9178390377054, 778.6534991928651, 5522.834314970557, 5905.966455870205, 6491.318411566812, 6649.767258145942, 6739.865202695919, 753.7248904149945, 854.1586537260794, 869.7869022089628, 885.1590988560893, 901.3730216958322, 3891.552471465761, 4043.474348334698, 4050.743396713019, 4088.2436749201493, 4175.64507016907, 13208.207507549578, 13695.726085478234, 13697.452101916011, 13736.175829450265, 13761.931190343985, 13613.523526676718, 13615.591532254048, 13617.0, 13620.93189534046, 13648.590542283748, 9115.042920493386, 9949.502524873727, 9970.45162516054, 10160.187185025181, 10200.183985281159, 6154.107654389999, 6226.097215903578, 6309.747610095545, 6321.589096708699, 6412.134361861089, 12063.939680301542, 12437.315947622968, 12463.439145238304, 12472.625821225378, 12476.937615312228, 10512.57949682004, 11454.599089031844, 11631.88368116342, 11802.645920622395, 12601.487821461484, 7466.925330746841, 8336.833263334578, 8348.415610907177, 8351.457155284887, 8364.330853531581, 9926.751831204278, 9928.503574821374, 9954.153896918842, 9971.900280997388, 9972.950135249568, 8679.566021699016, 8841.557922103997, 8891.0, 9008.189262966365, 9073.818523629623, 8421.621027053674, 8518.914810851813, 8546.188112129337, 8651.653933842575, 8658.39391242607, 7308.96345518269, 7338.156112047183, 7353.448491363062, 7438.033055702724, 7441.479096463196, 5479.0959491683425, 5534.529564987021, 5565.55475561946, 5600.187972744011, 5813.128030795415, 4868.9756551217015, 5691.4877661010705, 5811.608276686371, 6064.514838812796, 6083.361247069171], "value": 5116.776364100877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.3129896701431, 357.98031108289035, 394.046407693382, 404.17346526353606, 405.7606012452652, 5890.0, 6048.697565121737, 6081.331053584102, 6205.348438413969, 6264.248290205171, 4294.935575966363, 5752.7986520471795, 5922.845045216184, 5937.3391887368425, 6086.604370715901, 4128.605408862867, 4671.878749100226, 4770.594499467541, 4802.759862006903, 4808.629540580935, 4557.977210113945, 4681.625469962411, 4734.218853889104, 4789.640776941726, 4804.615630749532, 4002.577152246769, 4775.4985726498735, 4776.737279449629, 4795.688280261789, 4803.823063349484, 4234.089670720795, 4742.241241401359, 4779.952200477986, 4782.450018247911, 4800.519948005206, 3878.2437424702193, 3895.8831235062994, 4212.136512015029, 4247.384129301265, 4265.808038638238, 5351.785928562853, 5504.80733174341, 5935.49548288396, 5940.584159108864, 5990.8502287442725, 4813.0, 5485.396606373296, 5501.449855014506, 5507.476789704971, 5516.531094856942, 4698.788554515055, 6347.936520634781, 6390.0, 6440.613563186177, 6611.636360000198, 3739.7756134631736, 4482.193205223059, 4682.180618391768, 4692.36653051837, 4711.623070154379, 6573.0, 6588.835279118012, 6752.5948443093575, 6893.0005149253575, 6897.172339319263, 3704.4258139988347, 4402.449693788277, 4462.732236065846, 4467.687261891701, 4469.396631454777, 4251.87244382669, 4400.207962566768, 4551.226291530382, 4553.0, 4553.772311384418, 4606.401167848158, 5120.564751996122, 5162.92255616166, 5174.896502069938, 5333.666583354161, 5143.922841157386, 5831.950248955229, 6474.417302442803, 6506.958886578125, 6537.280899101095, 1442.3365251984205, 1473.5432016075051, 1526.5267766992047, 1551.1313664348002, 1553.0448774003905, 878.4246318661287, 894.2398960883327, 1001.5077534473727, 1025.8820235672845, 1028.5268776362807, 5121.129408000649, 5406.324209473816, 6049.245718741516, 6114.296855861544, 6125.571210015345, 6091.14723938649, 6240.251169859646, 6714.959181326903, 6729.596224226562, 6749.751296010254, 5076.416212135578, 5316.813911513079, 5724.107606827314, 6090.634561926298, 6143.232095988002, 2673.639058727047, 3136.090533745234, 3489.7196635162863, 3521.4189658706027, 3522.330757156132, 6574.145361103026, 6939.653017349112, 6943.51395402317, 7001.0, 7063.328983746535, 7021.5787052777, 7055.0, 7081.575105493687, 7095.062240456493, 7116.217216106224, 6751.56114852533, 6943.166819981635, 7019.78748807299, 7026.859462810715, 7037.542559733499, 5531.39589519035, 6444.581102228341, 6805.387515123464, 6851.48613853953, 6882.655867206519, 6121.755129794937, 6319.46284565817, 6554.279029306677, 6667.933320666732, 6776.728930842711, 5503.669779813224, 6964.79105626832, 7004.509684322048, 7062.823429414306, 7064.505484616027, 3761.7931013794505, 4442.445144199504, 4461.308497182942, 4477.910441791081, 4483.417155769859, 5629.54963602919, 6600.636964966973, 6644.803935291694, 6690.565113267622, 6713.395794378529, 6516.478681705357, 6518.152640156749, 6533.803985880431, 6548.8035358939305, 6555.508336874658, 6273.870703273455, 6487.156669632917, 6492.513061520499, 6513.446357059703, 6540.0, 5785.334686511105, 6390.64851433157, 6424.0, 6427.517936154901, 6460.5463770653105, 5708.65748055118, 6208.751649934133, 6425.361532809104, 6449.1301370889505, 6559.147310849559, 4819.445763737215, 5495.422980587141, 6269.0, 6385.616862988236, 6428.453581445442], "value": 4748.982951861362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.902543156674, 722.6820199112393, 730.3208016544613, 734.6473692627541, 766.6090293950084, 14893.329800158992, 15633.218339083058, 15680.059196448206, 17490.82509174908, 18381.90809045956, 16866.24101915414, 17074.402395916113, 17074.804763666547, 17356.392526261585, 17656.558586035357, 958.7315551645551, 977.8533220016983, 988.4168340678998, 988.7330420786384, 991.2862738828032, 943.5612440215299, 973.0852998181722, 973.3624475968217, 983.0218932162472, 986.4722373530162, 1034.6223628375626, 1036.0106098675747, 1040.209440824971, 1040.5369610523321, 1046.3042077018774, 1028.9948550257238, 1033.204432586904, 1033.245730616654, 1036.4144258493927, 1036.9377837329785, 766.264386189259, 766.9884951725728, 767.1829501580798, 770.6493545436813, 772.2393442459173, 15676.843231567653, 15986.44047458345, 16157.676846463122, 16245.593860153473, 16540.83459165405, 14507.274636268145, 14743.705125897422, 14851.554453366416, 14945.70108597822, 15671.68656626861, 11123.833142502868, 11342.262752921033, 11704.590339338083, 11825.640051394113, 12199.573014944433, 2967.8901134956777, 3100.3334283129234, 3129.9217519561967, 3169.259576502447, 3172.397244523533, 48496.67875626536, 80904.19095809026, 103586.96413035924, 103742.73800833945, 107230.46384768104, 11628.244164129474, 11670.649880503599, 11760.35318057515, 11807.409629518661, 12077.094217933514, 24460.38849028753, 24479.877600611882, 24485.877570612032, 24500.387490312885, 24518.264452066465, 7964.2433968772875, 8007.799805004922, 8088.676452941816, 8205.056418511947, 8320.246496372081, 4041.373587094005, 4132.855350062733, 4181.853635122756, 4197.24449599069, 4208.494980602347, 4182.1008483176265, 4250.213710463574, 4265.893352666209, 4282.293421585466, 4299.849505267363, 848.6605357856783, 852.6887685994567, 859.5916939453834, 861.1388611388611, 880.55972013993, 458.317107509808, 573.8651416917021, 577.3937365765929, 579.3279795437272, 589.3340525206489, 9848.50757462124, 9985.650502232387, 10285.588576456857, 10391.740206494747, 10442.269041167197, 552.6711606594071, 574.2420005592578, 590.3476658292525, 605.8455093951014, 612.1796792298342, 4698.694584851974, 4790.592799612003, 4793.448753393404, 4803.313041617102, 4842.58837998767, 94586.0, 98412.0, 98664.01335986692, 99933.0, 102195.0, 66829.33170668231, 78379.0, 79719.60140199262, 80695.59652201702, 83749.58125209456, 52256.73871630617, 68608.0, 75349.0, 75395.62302188454, 76206.0, 9688.85466718014, 10190.337628054152, 10903.346058388865, 10915.345079295268, 11056.889431105586, 37138.44292335563, 37297.81351093227, 37813.62186378101, 38192.0, 38443.42334864923, 48483.0, 49183.0, 49216.75391623019, 49528.00943981172, 50043.49956500534, 13376.866231337952, 13506.054576179966, 13660.316984150952, 13849.0, 13955.511557095453, 14394.35225414838, 14878.330475128862, 14962.0, 15001.549953501413, 15046.699066018837, 13292.01239628127, 13550.325137827434, 13554.525591604246, 13703.246321452416, 13753.174809511462, 13615.0, 13625.114367566077, 13631.38658760378, 13785.931070344584, 14463.987520873437, 12779.297138657092, 13241.404136814059, 13262.336883155996, 13370.799438008633, 13522.0, 11022.614208502664, 11149.554017839195, 11167.0, 11796.03767456946, 12159.628159348682, 11074.5570177192, 11265.605703800327, 11295.774084518427, 11365.715857103638, 11450.885491145315], "value": 7593.482376988611, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 112.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 114.0, 110.0, 157.0, 143.0, 145.0, 141.0, 105.0, 173.0, 145.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 189.0, 186.0, 186.0, 194.0, 257.0, 185.0, 185.0, 187.0], "value": 193.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 63.0, 60.0, 92.0, 57.0, 58.0, 61.0, 58.0, 76.0, 60.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 88.0, 69.0, 70.0, 68.0, 81.0, 66.0, 69.0, 85.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 136.0, 89.0, 90.0, 90.0, 89.0, 90.0, 90.0, 89.0, 89.0], "value": 97.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 86.0, 86.0, 100.0, 83.0, 86.0, 87.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 64.0, 63.0, 61.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 61.0, 64.0, 63.0, 60.0, 60.0, 62.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 95.0, 97.0, 111.0, 106.0, 84.0, 83.0, 82.0, 85.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 143.0, 139.0, 147.0, 139.0, 160.0, 148.0, 179.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 233.0, 187.0, 186.0, 186.0, 187.0, 186.0, 184.0, 189.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 69.0, 67.0, 67.0, 68.0, 66.0, 67.0, 67.0], "value": 67.1, "unit": "score"}]}]} 15:54:40 INFO - Return code: 0 15:54:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:54:40 ERROR - # TBPL SUCCESS # 15:54:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:54:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:54:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:54:40 INFO - 2017-03-22 15:54:40.522 screenresolution[85354:738859] starting screenresolution argv=screenresolution get 15:54:40 INFO - 2017-03-22 15:54:40.526 screenresolution[85354:738859] Display 0: 1600x1200x32@60 15:54:40 INFO - 2017-03-22 15:54:40.532 screenresolution[85355:738862] starting screenresolution argv=screenresolution list 15:54:40 INFO - Available Modes on Display 0 15:54:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:54:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:54:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:54:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:54:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:54:40 INFO - Intel Iris: 15:54:40 INFO - Chipset Model: Intel Iris 15:54:40 INFO - Type: GPU 15:54:40 INFO - Bus: Built-In 15:54:40 INFO - VRAM (Dynamic, Max): 1536 MB 15:54:40 INFO - Vendor: Intel (0x8086) 15:54:40 INFO - Device ID: 0x0a2e 15:54:40 INFO - Revision ID: 0x0009 15:54:40 INFO - Displays: 15:54:40 INFO - Display: 15:54:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:54:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:54:40 INFO - Display Serial Number: 1600x1200 60 15:54:40 INFO - Main Display: Yes 15:54:40 INFO - Mirror: Off 15:54:40 INFO - Online: Yes 15:54:40 INFO - Rotation: Supported 15:54:40 INFO - Return code: 0 15:54:40 INFO - Running post-action listener: _resource_record_post_action 15:54:40 INFO - [mozharness: 2017-03-22 22:54:40.771587Z] Finished run-tests step (success) 15:54:40 INFO - Running post-run listener: _resource_record_post_run 15:54:40 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 249856; Write bytes: 512657408; Read time: 3; Write time: 1560 15:54:40 INFO - TinderboxPrint: CPU usage
53.4% 15:54:40 INFO - TinderboxPrint: I/O read bytes / time
249,856 / 3 15:54:40 INFO - TinderboxPrint: I/O write bytes / time
512,657,408 / 1,560 15:54:40 INFO - TinderboxPrint: CPU idle
2,246.6 (46.6%) 15:54:40 INFO - TinderboxPrint: CPU system
961.2 (20.0%) 15:54:40 INFO - TinderboxPrint: CPU user
1,609.9 (33.4%) 15:54:40 INFO - TinderboxPrint: Swap in / out
210,411,520 / 0 15:54:40 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202895872; Write bytes: 204888064; Read time: 15738; Write time: 435 15:54:40 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 249856; Write bytes: 297820160; Read time: 3; Write time: 1090 15:54:41 INFO - Running post-run listener: _upload_blobber_files 15:54:41 INFO - Blob upload gear active. 15:54:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:54:41 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 15:54:41 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:54:41 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:54:41 INFO - (blobuploader) - INFO - Open directory for files ... 15:54:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:54:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:54:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:42 INFO - (blobuploader) - INFO - Done attempting. 15:54:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:54:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:54:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:43 INFO - (blobuploader) - INFO - Done attempting. 15:54:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:54:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:54:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:44 INFO - (blobuploader) - INFO - Done attempting. 15:54:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:54:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:54:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:46 INFO - (blobuploader) - INFO - Done attempting. 15:54:46 INFO - (blobuploader) - INFO - Iteration through files over. 15:54:46 INFO - Return code: 0 15:54:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:54:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:54:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9b9e49f67b25aebe77b93ef9be4901d7911214048c47309b14bb23bb5bd3c4c9c953d63ef493c26980a2676ef494feb77717a3c180c0ccb777662d0e2d8b2b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8aa356023490f5ca7e9282fcda6d53ceb4d267020733c0f2ae4c1b05b7b3b81312fcbad8bd065b4c6ec2bb1cfaab45e05004da30a3757ac2f6058417a2e561e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc772c85924afc61b11cb5dac9c1bc26e5e24993627f92f5a0ec0e56d12a60ad1a57473bd3df25f8557205641157e6c7aa6d3d25d80f0fe16723004d632a14c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a32c9939f0ffd0bfc20e5a3c95af89ab46e8c44a4df9013881439540f65984e00c7dfa59291417ea6faf8d96a2bfa75a1fcc0dc0592f2c1c03c981d4d8527f70"} 15:54:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:54:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:54:46 INFO - Contents: 15:54:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9b9e49f67b25aebe77b93ef9be4901d7911214048c47309b14bb23bb5bd3c4c9c953d63ef493c26980a2676ef494feb77717a3c180c0ccb777662d0e2d8b2b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8aa356023490f5ca7e9282fcda6d53ceb4d267020733c0f2ae4c1b05b7b3b81312fcbad8bd065b4c6ec2bb1cfaab45e05004da30a3757ac2f6058417a2e561e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc772c85924afc61b11cb5dac9c1bc26e5e24993627f92f5a0ec0e56d12a60ad1a57473bd3df25f8557205641157e6c7aa6d3d25d80f0fe16723004d632a14c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a32c9939f0ffd0bfc20e5a3c95af89ab46e8c44a4df9013881439540f65984e00c7dfa59291417ea6faf8d96a2bfa75a1fcc0dc0592f2c1c03c981d4d8527f70"} 15:54:46 INFO - Running post-run listener: copy_logs_to_upload_dir 15:54:46 INFO - Copying logs to upload dir... 15:54:46 INFO - mkdir: /builds/slave/test/build/upload/logs 15:54:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.827146 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-22 15:54:46.577809) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 15:54:46.580922) ========= 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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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/Pine/sha512/9b9e49f67b25aebe77b93ef9be4901d7911214048c47309b14bb23bb5bd3c4c9c953d63ef493c26980a2676ef494feb77717a3c180c0ccb777662d0e2d8b2b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8aa356023490f5ca7e9282fcda6d53ceb4d267020733c0f2ae4c1b05b7b3b81312fcbad8bd065b4c6ec2bb1cfaab45e05004da30a3757ac2f6058417a2e561e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc772c85924afc61b11cb5dac9c1bc26e5e24993627f92f5a0ec0e56d12a60ad1a57473bd3df25f8557205641157e6c7aa6d3d25d80f0fe16723004d632a14c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a32c9939f0ffd0bfc20e5a3c95af89ab46e8c44a4df9013881439540f65984e00c7dfa59291417ea6faf8d96a2bfa75a1fcc0dc0592f2c1c03c981d4d8527f70"} build_url:https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/JMps3zUfRzG-kD8JXfs0IA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9b9e49f67b25aebe77b93ef9be4901d7911214048c47309b14bb23bb5bd3c4c9c953d63ef493c26980a2676ef494feb77717a3c180c0ccb777662d0e2d8b2b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8aa356023490f5ca7e9282fcda6d53ceb4d267020733c0f2ae4c1b05b7b3b81312fcbad8bd065b4c6ec2bb1cfaab45e05004da30a3757ac2f6058417a2e561e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc772c85924afc61b11cb5dac9c1bc26e5e24993627f92f5a0ec0e56d12a60ad1a57473bd3df25f8557205641157e6c7aa6d3d25d80f0fe16723004d632a14c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a32c9939f0ffd0bfc20e5a3c95af89ab46e8c44a4df9013881439540f65984e00c7dfa59291417ea6faf8d96a2bfa75a1fcc0dc0592f2c1c03c981d4d8527f70"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 15:54:46.615803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:54:46.616165) ========= 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.zqDUCGuPLp/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.ZdwoyEAZSm/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:54:46.665382) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 15:54:46.665725) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 15:54:46.666077) ========= ========= Total master_lag: 0.16 =========