builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0089 starttime: 1483457457.32 results: success (0) buildid: 20170103065519 builduid: c14856f1d200418e9d155c824d6c8b3f revision: cad2ea346d06ec5a3a70eda912513201dff0c21e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.317498) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.317931) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.318204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.344649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.345027) ========= 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.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-01-03 07:30:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.7M=0.001s 2017-01-03 07:30:57 (13.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.516925) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.517236) ========= 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.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.026859 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:57.565722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 07:30:57.566074) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cad2ea346d06ec5a3a70eda912513201dff0c21e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cad2ea346d06ec5a3a70eda912513201dff0c21e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-01-03 07:30:57,661 truncating revision to first 12 chars 2017-01-03 07:30:57,661 Setting DEBUG logging. 2017-01-03 07:30:57,661 attempt 1/10 2017-01-03 07:30:57,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cad2ea346d06?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 07:30:58,484 unpacking tar archive at: mozilla-central-cad2ea346d06/testing/mozharness/ program finished with exit code 0 elapsedTime=1.096467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 07:30:58.686421) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:58.686798) ========= script_repo_revision: cad2ea346d06ec5a3a70eda912513201dff0c21e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:58.687187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:58.687456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 07:30:58.707420) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-03 07:30:58.707818) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:30:58 INFO - MultiFileLogger online at 20170103 07:30:58 in /builds/slave/test 07:30:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:30:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:58 INFO - {'append_to_log': False, 07:30:58 INFO - 'base_work_dir': '/builds/slave/test', 07:30:58 INFO - 'blob_upload_branch': 'Firefox', 07:30:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:58 INFO - 'branch': 'Firefox', 07:30:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:58 INFO - 'config_files': ('talos/mac_config.py',), 07:30:58 INFO - 'default_actions': ('clobber', 07:30:58 INFO - 'read-buildbot-config', 07:30:58 INFO - 'download-and-extract', 07:30:58 INFO - 'populate-webroot', 07:30:58 INFO - 'create-virtualenv', 07:30:58 INFO - 'install', 07:30:58 INFO - 'run-tests'), 07:30:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:58 INFO - 'download_minidump_stackwalk': True, 07:30:58 INFO - 'download_symbols': 'ondemand', 07:30:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:58 INFO - 'installer_path': 'installer.exe', 07:30:58 INFO - 'log_level': 'info', 07:30:58 INFO - 'log_name': 'talos', 07:30:58 INFO - 'log_to_console': True, 07:30:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:30:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:30:58 INFO - 'opt_config_files': (), 07:30:58 INFO - 'pip_index': False, 07:30:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:58 INFO - 'cmd': ('bash', 07:30:58 INFO - '-c', 07:30:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:58 INFO - 'enabled': True, 07:30:58 INFO - 'halt_on_failure': False, 07:30:58 INFO - 'name': 'check_screen_resolution'},), 07:30:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:58 INFO - 'cmd': ('bash', 07:30:58 INFO - '-c', 07:30:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:58 INFO - 'enabled': True, 07:30:58 INFO - 'halt_on_failure': False, 07:30:58 INFO - 'name': 'check_screen_resolution'},), 07:30:58 INFO - 'run_cmd_checks_enabled': True, 07:30:58 INFO - 'sps_profile': False, 07:30:58 INFO - 'sps_profile_interval': 0, 07:30:58 INFO - 'suite': 'dromaeojs', 07:30:58 INFO - 'system_bits': '32', 07:30:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:58 INFO - 'title': 't-yosemite-r7-0089', 07:30:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:58 INFO - 'use_talos_json': True, 07:30:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:58 INFO - 'work_dir': 'build'} 07:30:58 INFO - [mozharness: 2017-01-03 15:30:58.912844Z] Running clobber step. 07:30:58 INFO - Running pre-action listener: _resource_record_pre_action 07:30:58 INFO - Running main action method: clobber 07:30:58 INFO - rmtree: /builds/slave/test/build 07:30:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:01 INFO - Running post-action listener: _resource_record_post_action 07:31:01 INFO - [mozharness: 2017-01-03 15:31:01.201762Z] Finished clobber step (success) 07:31:01 INFO - [mozharness: 2017-01-03 15:31:01.201899Z] Running read-buildbot-config step. 07:31:01 INFO - Running pre-action listener: _resource_record_pre_action 07:31:01 INFO - Running main action method: read_buildbot_config 07:31:01 INFO - Using buildbot properties: 07:31:01 INFO - { 07:31:01 INFO - "project": "", 07:31:01 INFO - "product": "firefox", 07:31:01 INFO - "script_repo_revision": "production", 07:31:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:31:01 INFO - "repository": "", 07:31:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:31:01 INFO - "buildid": "20170103065519", 07:31:01 INFO - "pgo_build": "False", 07:31:01 INFO - "basedir": "/builds/slave/test", 07:31:01 INFO - "buildnumber": 67, 07:31:01 INFO - "slavename": "t-yosemite-r7-0089", 07:31:01 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:31:01 INFO - "platform": "yosemite_r7", 07:31:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:31:01 INFO - "branch": "mozilla-central", 07:31:01 INFO - "repo_path": "mozilla-central", 07:31:01 INFO - "revision": "cad2ea346d06ec5a3a70eda912513201dff0c21e", 07:31:01 INFO - "stage_platform": "macosx64", 07:31:01 INFO - "builduid": "c14856f1d200418e9d155c824d6c8b3f", 07:31:01 INFO - "slavebuilddir": "test" 07:31:01 INFO - } 07:31:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:31:01 INFO - Running post-action listener: _resource_record_post_action 07:31:01 INFO - [mozharness: 2017-01-03 15:31:01.205287Z] Finished read-buildbot-config step (success) 07:31:01 INFO - [mozharness: 2017-01-03 15:31:01.205423Z] Running download-and-extract step. 07:31:01 INFO - Running pre-action listener: _resource_record_pre_action 07:31:01 INFO - Running main action method: download_and_extract 07:31:01 INFO - mkdir: /builds/slave/test/build/tests 07:31:01 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')]} 07:31:01 INFO - trying https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:31:01 INFO - Downloading https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:31:02 INFO - Downloaded 1199 bytes. 07:31:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:31:02 INFO - Using the following test package requirements: 07:31:02 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:31:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:31:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:31:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'jsshell-mac.zip'], 07:31:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:31:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:31:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:31:02 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:31:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:31:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:31:02 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:31:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:31:03 INFO - Expected file size: 16300475 07:31:03 INFO - Obtained file size: 16300475 07:31:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:31:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:31:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:10 INFO - Fetch https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:31:11 INFO - Expected file size: 16300475 07:31:11 INFO - Obtained file size: 16300475 07:31:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:31:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:31:17 INFO - Fetch https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:31:18 INFO - Expected file size: 14409776 07:31:18 INFO - Obtained file size: 14409776 07:31:18 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')]} 07:31:18 INFO - trying https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:18 INFO - Downloading https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:31:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:31:20 INFO - Downloaded 69762954 bytes. 07:31:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:20 INFO - mkdir: /builds/slave/test/properties 07:31:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:20 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:20 INFO - Contents: 07:31:20 INFO - build_url:https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:21 INFO - Running post-action listener: _resource_record_post_action 07:31:21 INFO - Running post-action listener: set_extra_try_arguments 07:31:21 INFO - [mozharness: 2017-01-03 15:31:21.614469Z] Finished download-and-extract step (success) 07:31:21 INFO - [mozharness: 2017-01-03 15:31:21.614592Z] Running populate-webroot step. 07:31:21 INFO - Running pre-action listener: _resource_record_pre_action 07:31:21 INFO - Running main action method: populate_webroot 07:31:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:31:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:31:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:31:21 INFO - '--tppagecycles', 07:31:21 INFO - '7'], 07:31:21 INFO - 'tests': ['tsvgm']}}, 07:31:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:31:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:31:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:31:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:31:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['damp', 'tps']}, 07:31:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'tests': ['damp', 'tps']}, 07:31:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['dromaeo_dom']}, 07:31:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:31:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['basic_compositor_video']}, 07:31:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:31:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'other-e10s': {'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['a11yr', 07:31:21 INFO - 'ts_paint', 07:31:21 INFO - 'tpaint', 07:31:21 INFO - 'sessionrestore', 07:31:21 INFO - 'sessionrestore_no_auto_restore', 07:31:21 INFO - 'tabpaint']}, 07:31:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['tsvgx', 07:31:21 INFO - 'tsvgr_opacity', 07:31:21 INFO - 'tart', 07:31:21 INFO - 'tscrollx', 07:31:21 INFO - 'cart', 07:31:21 INFO - 'tsvg_static']}, 07:31:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:31:21 INFO - 'tsvgr_opacity', 07:31:21 INFO - 'tart', 07:31:21 INFO - 'tscrollx', 07:31:21 INFO - 'cart', 07:31:21 INFO - 'tsvg_static']}, 07:31:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'talos_options': ['--disable-e10s'], 07:31:21 INFO - 'tests': ['tp5o']}, 07:31:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:31:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'talos_options': ['--disable-e10s', 07:31:21 INFO - '--xperf_path', 07:31:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:21 INFO - 'tests': ['tp5n']}, 07:31:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:31:21 INFO - 'talos_options': ['--xperf_path', 07:31:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:21 INFO - 'tests': ['tp5n']}}, 07:31:21 INFO - 'talos.zip': {'path': '', 07:31:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:31:21 INFO - Running post-action listener: _resource_record_post_action 07:31:21 INFO - [mozharness: 2017-01-03 15:31:21.633017Z] Finished populate-webroot step (success) 07:31:21 INFO - [mozharness: 2017-01-03 15:31:21.633127Z] Running create-virtualenv step. 07:31:21 INFO - Running pre-action listener: _resource_record_pre_action 07:31:21 INFO - Running main action method: create_virtualenv 07:31:21 INFO - Creating virtualenv /builds/slave/test/build/venv 07:31:21 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 07:31:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:31:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:31:21 INFO - Using real prefix '/tools/python27' 07:31:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:31:22 INFO - Installing distribute.............................................................................................................................................................................................done. 07:31:25 INFO - Installing pip.................done. 07:31:25 INFO - Return code: 0 07:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:31:25 INFO - Reading from file tmpfile_stdout 07:31:25 INFO - Output received: 07:31:25 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) 07:31:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:25 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 07:31:25 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 07:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:25 INFO - 'HOME': '/Users/cltbld', 07:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:25 INFO - 'LOGNAME': 'cltbld', 07:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:25 INFO - 'MOZ_NO_REMOTE': '1', 07:31:25 INFO - 'NO_EM_RESTART': '1', 07:31:25 INFO - 'PAGER': '/bin/cat', 07:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:25 INFO - 'PWD': '/builds/slave/test', 07:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:25 INFO - 'SHELL': '/bin/bash', 07:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:25 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:25 INFO - 'USER': 'cltbld', 07:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:25 INFO - 'XPC_FLAGS': '0x0', 07:31:25 INFO - 'XPC_SERVICE_NAME': '0', 07:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:25 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 07:31:25 INFO - Cleaning up... 07:31:25 INFO - Return code: 0 07:31:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:25 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 07:31:25 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 07:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:25 INFO - 'HOME': '/Users/cltbld', 07:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:25 INFO - 'LOGNAME': 'cltbld', 07:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:25 INFO - 'MOZ_NO_REMOTE': '1', 07:31:25 INFO - 'NO_EM_RESTART': '1', 07:31:25 INFO - 'PAGER': '/bin/cat', 07:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:25 INFO - 'PWD': '/builds/slave/test', 07:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:25 INFO - 'SHELL': '/bin/bash', 07:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:25 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:25 INFO - 'USER': 'cltbld', 07:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:25 INFO - 'XPC_FLAGS': '0x0', 07:31:25 INFO - 'XPC_SERVICE_NAME': '0', 07:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:26 INFO - Downloading/unpacking psutil>=3.1.1 07:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:29 INFO - Installing collected packages: psutil 07:31:29 INFO - Running setup.py install for psutil 07:31:29 INFO - building 'psutil._psutil_osx' extension 07:31:29 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 07:31:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:31:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:31:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:31:30 INFO - building 'psutil._psutil_posix' extension 07:31:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:31:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:31:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:31:30 INFO - ^ 07:31:30 INFO - 1 warning generated. 07:31:30 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 07:31:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:30 INFO - Successfully installed psutil 07:31:30 INFO - Cleaning up... 07:31:30 INFO - Return code: 0 07:31:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:30 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:30 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 07:31:30 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 07:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:30 INFO - 'HOME': '/Users/cltbld', 07:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:30 INFO - 'LOGNAME': 'cltbld', 07:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:30 INFO - 'MOZ_NO_REMOTE': '1', 07:31:30 INFO - 'NO_EM_RESTART': '1', 07:31:30 INFO - 'PAGER': '/bin/cat', 07:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:30 INFO - 'PWD': '/builds/slave/test', 07:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:30 INFO - 'SHELL': '/bin/bash', 07:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:30 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:30 INFO - 'USER': 'cltbld', 07:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:30 INFO - 'XPC_FLAGS': '0x0', 07:31:30 INFO - 'XPC_SERVICE_NAME': '0', 07:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:31:34 INFO - Installing collected packages: mozsystemmonitor 07:31:34 INFO - Running setup.py install for mozsystemmonitor 07:31:34 INFO - Successfully installed mozsystemmonitor 07:31:34 INFO - Cleaning up... 07:31:34 INFO - Return code: 0 07:31:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:34 INFO - 'HOME': '/Users/cltbld', 07:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:34 INFO - 'LOGNAME': 'cltbld', 07:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:34 INFO - 'MOZ_NO_REMOTE': '1', 07:31:34 INFO - 'NO_EM_RESTART': '1', 07:31:34 INFO - 'PAGER': '/bin/cat', 07:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:34 INFO - 'PWD': '/builds/slave/test', 07:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:34 INFO - 'SHELL': '/bin/bash', 07:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:34 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:34 INFO - 'USER': 'cltbld', 07:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:34 INFO - 'XPC_FLAGS': '0x0', 07:31:34 INFO - 'XPC_SERVICE_NAME': '0', 07:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:31:35 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 07:31:35 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 07:31:35 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 07:31:35 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 07:31:35 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 07:31:35 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 07:31:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:38 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:38 INFO - warning: no files found matching '*.txt' 07:31:38 INFO - no previously-included directories found matching 'build' 07:31:38 INFO - no previously-included directories found matching 'dist' 07:31:38 INFO - no previously-included directories found matching '.git*' 07:31:38 INFO - Installing collected packages: jsonschema, functools32 07:31:38 INFO - Running setup.py install for functools32 07:31:38 INFO - warning: no files found matching '*.txt' 07:31:38 INFO - no previously-included directories found matching 'build' 07:31:38 INFO - no previously-included directories found matching 'dist' 07:31:38 INFO - no previously-included directories found matching '.git*' 07:31:38 INFO - Successfully installed jsonschema functools32 07:31:38 INFO - Cleaning up... 07:31:39 INFO - Return code: 0 07:31:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:39 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 07:31:39 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 07:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:39 INFO - 'HOME': '/Users/cltbld', 07:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:39 INFO - 'LOGNAME': 'cltbld', 07:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:39 INFO - 'MOZ_NO_REMOTE': '1', 07:31:39 INFO - 'NO_EM_RESTART': '1', 07:31:39 INFO - 'PAGER': '/bin/cat', 07:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:39 INFO - 'PWD': '/builds/slave/test', 07:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:39 INFO - 'SHELL': '/bin/bash', 07:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:39 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:39 INFO - 'USER': 'cltbld', 07:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:39 INFO - 'XPC_FLAGS': '0x0', 07:31:39 INFO - 'XPC_SERVICE_NAME': '0', 07:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:31:39 INFO - Cleaning up... 07:31:39 INFO - Return code: 0 07:31:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:39 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 07:31:39 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 07:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:39 INFO - 'HOME': '/Users/cltbld', 07:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:39 INFO - 'LOGNAME': 'cltbld', 07:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:39 INFO - 'MOZ_NO_REMOTE': '1', 07:31:39 INFO - 'NO_EM_RESTART': '1', 07:31:39 INFO - 'PAGER': '/bin/cat', 07:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:39 INFO - 'PWD': '/builds/slave/test', 07:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:39 INFO - 'SHELL': '/bin/bash', 07:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:39 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:39 INFO - 'USER': 'cltbld', 07:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:39 INFO - 'XPC_FLAGS': '0x0', 07:31:39 INFO - 'XPC_SERVICE_NAME': '0', 07:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:39 INFO - Downloading/unpacking blobuploader==1.2.4 07:31:39 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 07:31:39 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 07:31:39 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 07:31:39 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 07:31:39 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 07:31:39 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 07:31:42 INFO - Downloading blobuploader-1.2.4.tar.gz 07:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:31:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:43 INFO - Downloading docopt-0.6.1.tar.gz 07:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:31:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:31:43 INFO - Running setup.py install for blobuploader 07:31:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:31:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:31:43 INFO - Running setup.py install for requests 07:31:44 INFO - Running setup.py install for docopt 07:31:44 INFO - Successfully installed blobuploader requests docopt 07:31:44 INFO - Cleaning up... 07:31:44 INFO - Return code: 0 07:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:44 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')]} 07:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:44 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')]} 07:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:44 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:44 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 07:31:44 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 07:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:44 INFO - 'HOME': '/Users/cltbld', 07:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:44 INFO - 'LOGNAME': 'cltbld', 07:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:44 INFO - 'MOZ_NO_REMOTE': '1', 07:31:44 INFO - 'NO_EM_RESTART': '1', 07:31:44 INFO - 'PAGER': '/bin/cat', 07:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:44 INFO - 'PWD': '/builds/slave/test', 07:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:44 INFO - 'SHELL': '/bin/bash', 07:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:44 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:44 INFO - 'USER': 'cltbld', 07:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:44 INFO - 'XPC_FLAGS': '0x0', 07:31:44 INFO - 'XPC_SERVICE_NAME': '0', 07:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ke8b3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-PIjAga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-NvcGeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-OmgiCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-mJp01K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-NeITy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-gDLr46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Fwq8hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-e7Ii55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-oM8v8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-JNUEeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-0Aqzkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-JEwXAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-XOxOqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-E3Ydip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ABCkc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-YsoqrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:46 INFO - Running setup.py install for manifestparser 07:31:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:46 INFO - Running setup.py install for mozcrash 07:31:47 INFO - Running setup.py install for mozdebug 07:31:47 INFO - Running setup.py install for mozdevice 07:31:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Running setup.py install for mozfile 07:31:47 INFO - Running setup.py install for mozhttpd 07:31:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Running setup.py install for mozinfo 07:31:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Running setup.py install for mozInstall 07:31:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:47 INFO - Running setup.py install for mozleak 07:31:48 INFO - Running setup.py install for mozlog 07:31:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Running setup.py install for moznetwork 07:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Running setup.py install for mozprocess 07:31:48 INFO - Running setup.py install for mozprofile 07:31:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Running setup.py install for mozrunner 07:31:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:48 INFO - Running setup.py install for mozscreenshot 07:31:49 INFO - Running setup.py install for moztest 07:31:49 INFO - Running setup.py install for mozversion 07:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:49 INFO - Cleaning up... 07:31:49 INFO - Return code: 0 07:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:49 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:49 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 07:31:49 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 07:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:49 INFO - 'HOME': '/Users/cltbld', 07:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:49 INFO - 'LOGNAME': 'cltbld', 07:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:49 INFO - 'MOZ_NO_REMOTE': '1', 07:31:49 INFO - 'NO_EM_RESTART': '1', 07:31:49 INFO - 'PAGER': '/bin/cat', 07:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:49 INFO - 'PWD': '/builds/slave/test', 07:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:49 INFO - 'SHELL': '/bin/bash', 07:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:49 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:49 INFO - 'USER': 'cltbld', 07:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:49 INFO - 'XPC_FLAGS': '0x0', 07:31:49 INFO - 'XPC_SERVICE_NAME': '0', 07:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-l9tPQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:49 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)) 07:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-rDpVDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:49 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)) 07:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-QkGa1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:49 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)) 07:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-oHNQY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-OWmTH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-_fyDtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-SmBGXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-V8e24e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-6Ttl7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ffwmuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-F93BLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:50 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)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-9bGKYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-I27seJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:51 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)) 07:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-VIF8Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:51 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)) 07:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-9u4pJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:51 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)) 07:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-4pbHUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:51 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)) 07:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-bKhnU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:51 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)) 07:31:51 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)) 07:31:51 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)) 07:31:51 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)) 07:31:51 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)) 07:31:51 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)) 07:31:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:51 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 07:31:51 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 07:31:51 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 07:31:51 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 07:31:51 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 07:31:51 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 07:31:54 INFO - Downloading blessings-1.6.tar.gz 07:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:54 INFO - Installing collected packages: blessings 07:31:54 INFO - Running setup.py install for blessings 07:31:54 INFO - Successfully installed blessings 07:31:54 INFO - Cleaning up... 07:31:54 INFO - Return code: 0 07:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:54 INFO - Reading from file tmpfile_stdout 07:31:54 INFO - Current package versions: 07:31:54 INFO - blessings == 1.6 07:31:54 INFO - blobuploader == 1.2.4 07:31:54 INFO - docopt == 0.6.1 07:31:54 INFO - functools32 == 3.2.3-2 07:31:54 INFO - jsonschema == 2.5.1 07:31:54 INFO - manifestparser == 1.1 07:31:54 INFO - mozInstall == 1.12 07:31:54 INFO - mozcrash == 1.0 07:31:54 INFO - mozdebug == 0.1 07:31:54 INFO - mozdevice == 0.49 07:31:54 INFO - mozfile == 1.2 07:31:54 INFO - mozhttpd == 0.7 07:31:54 INFO - mozinfo == 0.9 07:31:54 INFO - mozleak == 0.1 07:31:54 INFO - mozlog == 3.4 07:31:54 INFO - moznetwork == 0.27 07:31:54 INFO - mozprocess == 0.23 07:31:54 INFO - mozprofile == 0.28 07:31:54 INFO - mozrunner == 6.13 07:31:54 INFO - mozscreenshot == 0.1 07:31:54 INFO - mozsystemmonitor == 0.3 07:31:54 INFO - moztest == 0.8 07:31:54 INFO - mozversion == 1.4 07:31:54 INFO - psutil == 3.1.1 07:31:54 INFO - requests == 1.2.3 07:31:54 INFO - wsgiref == 0.1.2 07:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:54 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:54 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 07:31:54 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 07:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:54 INFO - 'HOME': '/Users/cltbld', 07:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:54 INFO - 'LOGNAME': 'cltbld', 07:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:54 INFO - 'MOZ_NO_REMOTE': '1', 07:31:54 INFO - 'NO_EM_RESTART': '1', 07:31:54 INFO - 'PAGER': '/bin/cat', 07:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:54 INFO - 'PWD': '/builds/slave/test', 07:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:54 INFO - 'SHELL': '/bin/bash', 07:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:54 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:54 INFO - 'USER': 'cltbld', 07:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:54 INFO - 'XPC_FLAGS': '0x0', 07:31:54 INFO - 'XPC_SERVICE_NAME': '0', 07:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 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)) 07:31:55 INFO - Cleaning up... 07:31:55 INFO - Return code: 0 07:31:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:55 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')]} 07:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:55 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')]} 07:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:55 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 0x10c90eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c911768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc641d8f9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, '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.vCg96v2caG/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.62uIgPA3dw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 07:31:55 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 07:31:55 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 07:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:55 INFO - 'HOME': '/Users/cltbld', 07:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:55 INFO - 'LOGNAME': 'cltbld', 07:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:55 INFO - 'MOZ_NO_REMOTE': '1', 07:31:55 INFO - 'NO_EM_RESTART': '1', 07:31:55 INFO - 'PAGER': '/bin/cat', 07:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:55 INFO - 'PWD': '/builds/slave/test', 07:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:55 INFO - 'SHELL': '/bin/bash', 07:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:31:55 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:55 INFO - 'USER': 'cltbld', 07:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:55 INFO - 'XPC_FLAGS': '0x0', 07:31:55 INFO - 'XPC_SERVICE_NAME': '0', 07:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:55 INFO - Cleaning up... 07:31:55 INFO - Return code: 0 07:31:55 INFO - Running post-action listener: _resource_record_post_action 07:31:55 INFO - Running post-action listener: _start_resource_monitoring 07:31:55 INFO - Starting resource monitoring. 07:31:55 INFO - [mozharness: 2017-01-03 15:31:55.336821Z] Finished create-virtualenv step (success) 07:31:55 INFO - [mozharness: 2017-01-03 15:31:55.337744Z] Running install step. 07:31:55 INFO - Running pre-action listener: _resource_record_pre_action 07:31:55 INFO - Running main action method: install 07:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:55 INFO - Reading from file tmpfile_stdout 07:31:55 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:55 INFO - Reading from file tmpfile_stdout 07:31:55 INFO - Output received: 07:31:55 INFO - Usage: mozinstall [options] installer 07:31:55 INFO - Options: 07:31:55 INFO - -h, --help show this help message and exit 07:31:55 INFO - -d DEST, --destination=DEST 07:31:55 INFO - Directory to install application into. [default: 07:31:55 INFO - "/builds/slave/test"] 07:31:55 INFO - --app=APP Application being installed. [default: firefox] 07:31:55 INFO - mkdir: /builds/slave/test/build/application 07:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:32:17 INFO - Reading from file tmpfile_stdout 07:32:17 INFO - Output received: 07:32:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:32:17 INFO - Running post-action listener: _resource_record_post_action 07:32:17 INFO - [mozharness: 2017-01-03 15:32:17.456580Z] Finished install step (success) 07:32:17 INFO - [mozharness: 2017-01-03 15:32:17.456736Z] Running run-tests step. 07:32:17 INFO - Running pre-action listener: _resource_record_pre_action 07:32:17 INFO - Running main action method: run_tests 07:32:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:17 INFO - 2017-01-03 07:32:17.471 screenresolution[1630:7640] starting screenresolution argv=screenresolution get 07:32:17 INFO - 2017-01-03 07:32:17.473 screenresolution[1630:7640] Display 0: 1600x1200x32@60 07:32:17 INFO - 2017-01-03 07:32:17.479 screenresolution[1631:7642] starting screenresolution argv=screenresolution list 07:32:17 INFO - Available Modes on Display 0 07:32:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:17 INFO - Intel Iris: 07:32:17 INFO - Chipset Model: Intel Iris 07:32:17 INFO - Type: GPU 07:32:17 INFO - Bus: Built-In 07:32:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:17 INFO - Vendor: Intel (0x8086) 07:32:17 INFO - Device ID: 0x0a2e 07:32:17 INFO - Revision ID: 0x0009 07:32:17 INFO - Displays: 07:32:17 INFO - Display: 07:32:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:17 INFO - Display Serial Number: 1600x1200 60 07:32:17 INFO - Main Display: Yes 07:32:17 INFO - Mirror: Off 07:32:17 INFO - Online: Yes 07:32:17 INFO - Rotation: Supported 07:32:17 INFO - Return code: 0 07:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:17 INFO - Python 2.7.3 07:32:17 INFO - Return code: 0 07:32:17 INFO - grabbing minidump binary from tooltool 07:32:17 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')]} 07:32:17 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 0x10c6ecd30>, '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 0x10c613db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:17 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 07:32:17 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 07:32:17 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 07:32:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:17 INFO - Return code: 0 07:32:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 07:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0089 --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 07:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vCg96v2caG/Render', 07:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:17 INFO - 'HOME': '/Users/cltbld', 07:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:17 INFO - 'LOGNAME': 'cltbld', 07:32:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:17 INFO - 'MOZ_NO_REMOTE': '1', 07:32:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:17 INFO - 'NO_EM_RESTART': '1', 07:32:17 INFO - 'PAGER': '/bin/cat', 07:32:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:17 INFO - 'PWD': '/builds/slave/test', 07:32:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:17 INFO - 'SHELL': '/bin/bash', 07:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.62uIgPA3dw/Listeners', 07:32:17 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 07:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:17 INFO - 'USER': 'cltbld', 07:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:17 INFO - 'XPC_FLAGS': '0x0', 07:32:17 INFO - 'XPC_SERVICE_NAME': '0', 07:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 07:32:18 INFO - mozversion application_buildid: 20170103065519 07:32:18 INFO - mozversion application_changeset: cad2ea346d06ec5a3a70eda912513201dff0c21e 07:32:18 INFO - mozversion application_display_name: Nightly 07:32:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:18 INFO - mozversion application_name: Firefox 07:32:18 INFO - mozversion application_remotingname: firefox 07:32:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:32:18 INFO - mozversion application_vendor: Mozilla 07:32:18 INFO - mozversion application_version: 53.0a1 07:32:18 INFO - mozversion platform_buildid: 20170103065519 07:32:18 INFO - mozversion platform_changeset: cad2ea346d06ec5a3a70eda912513201dff0c21e 07:32:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:32:18 INFO - mozversion platform_version: 53.0a1 07:32:18 INFO - using testdate: 1483457538 07:32:18 INFO - actual date: 1483457538 07:32:18 INFO - starting webserver on 'localhost:49242' 07:32:18 INFO - SUITE-START | Running 2 tests 07:32:18 INFO - TEST-START | dromaeo_css 07:32:18 INFO - operating with platform_type : mac_ 07:32:18 INFO - Initialising browser for dromaeo_css test... 07:32:18 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpl1s3Wk/profile http://localhost:49242/getInfo.html) 07:32:18 INFO - PROCESS | 1641 | 1483457538601 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:32:20 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 07:32:20 INFO - PROCESS | 1641 | colorDepth:24 07:32:20 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 07:32:20 INFO - PROCESS | 1641 | __metrics 07:32:20 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:20 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:21 INFO - TEST-INFO | 1641: exit 0 07:32:21 INFO - Browser initialized. 07:32:21 INFO - Running cycle 1/1 for dromaeo_css test... 07:32:21 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpl1s3Wk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:33 INFO - PROCESS | 1643 | RSS: Main: 234295296 07:32:33 INFO - PROCESS | 1643 | 07:33:24 INFO - PROCESS | 1643 | 1483457604386 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:35:37 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 07:35:38 INFO - PROCESS | 1643 | RSS: Main: 367878144 07:35:38 INFO - PROCESS | 1643 | 07:37:22 INFO - PROCESS | 1643 | ************************* 07:37:22 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 07:37:22 INFO - PROCESS | 1643 | 07:37:22 INFO - PROCESS | 1643 | Full message: ReferenceError: fetch is not defined 07:37:22 INFO - PROCESS | 1643 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:37:22 INFO - PROCESS | 1643 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:37:22 INFO - PROCESS | 1643 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:37:22 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:37:22 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:37:22 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:37:22 INFO - PROCESS | 1643 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:37:22 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:37:22 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:37:22 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:37:22 INFO - PROCESS | 1643 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:37:22 INFO - PROCESS | 1643 | 07:37:22 INFO - PROCESS | 1643 | ************************* 07:38:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 07:38:42 INFO - PROCESS | 1643 | RSS: Main: 358912000 07:38:42 INFO - PROCESS | 1643 | 07:41:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 07:41:45 INFO - PROCESS | 1643 | RSS: Main: 334729216 07:41:45 INFO - PROCESS | 1643 | 07:44:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 07:44:48 INFO - PROCESS | 1643 | RSS: Main: 336621568 07:44:48 INFO - PROCESS | 1643 | 07:47:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 07:47:52 INFO - PROCESS | 1643 | RSS: Main: 332222464 07:47:52 INFO - PROCESS | 1643 | 07:50:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 07:50:56 INFO - PROCESS | 1643 | RSS: Main: 382042112 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | __start_tp_report 07:50:56 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 07:50:56 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 07:50:56 INFO - PROCESS | 1643 | |i|pagename|runs| 07:50:56 INFO - PROCESS | 1643 | |0;dojo.html;3351.3464874349497;3353.8323083845803;3367.2255381262316;3369.9831500842492;3427.67437093476;5618.943810561904;5677.460641239086;5679.858003549908;5701.857453563658;5706.029974904266;5688.516476099526;5720.0561907285355;5725.427457254273;5728.255326807509;5729.0833466645345;5158.097332966744;5190.377154741436;5202.6358154928985;5220.425753167148;5245.108331583621;5232.476752324776;5249.527542521172;5267.736613169345;5272.182811664198;5284.841454756344;5235.397929238129;5265.947340526584;5284.788608455658;5331.093714068598;5362.0616392131415;5288.153895376721;5299.178627312773;5300.496452836975;5310.4424035476495;5321.787128514855;591.483502620405;5181.870453238662;5201.323827902386;5226.973865130688;5270.1040823060175;5686.232358631573;5705.6291341063115;5756.136579513065;5774.393688662672;5780.797672081457;6282.528810339243;6288.937110628926;6292.402221788922;6306.810795676137;6310.810675679737;6128.900367973349;6179.969100154471;6214.844628884269;6242.406971337761;6284.874302513924;5853.239078919713;5859.853503662443;5872.765089396376;5896.380880007623;5909.586328956974;6306.432421082033;6309.274433440121;6315;6317.178766760292;6328.746850125944;6036.094585812076;6044.093385992049;6103.496225665457;6181.629102253879;6187.659678717714;5225.738713064332;6209.5032397408095;6252.74989000444;6279.058141278845;6308.463780578611;6250.406211409909;6256.343083976117;6268.623882567061;6278.968605156945;6284.622922624657;6086.300075491286;6113.141862489121;6197.25632924052;6278;6284.528660350492;4403;4939.333190019373;4972.477889821589;4982.352294201731;4996.775145118479;3774.6036666149657;4407.382966384709;4423.734575925455;4435.046465010038;4441.800118994653;5664.348599911063;5721.57088218377;5727.455891690282;5728.627639203439;5826.94173058264;6248.593841400295;6274.309825919146;6300.243970723543;6304.211973503312;6307.463865571386;5822.854428639318;5829.562782791308;5836.9785287574505;5848;5890.175375447441;5274.7890084396195;5437;5440.646357986798;5446.537044351196;5462.8361149165585;5970.701464926823;6241;6280.2777680566405;6300.243970723543;6311.779087731908;6142.955697531341;6297.653629050446;6298.653574053471;6307.432331090224;6310.4320611143385;6270.059491076375;6294.24469063697;6330.082138090043;6335.714892829926;6347.904781428375;5992.67040312787;6038.7282572283475;6111.77406523465;6135.509159267337;6217.844553886189;6255.468285195628;6281.811545653638;6285.057241413824;6293.087502312231;6299.937000629935;6061.212042434456;6131.96934015327;6133.785317513865;6149.723262453111;6151.26184857802;5977.7310021050025;6042.063480160582;6096.0855871619615;6098;6166.660833654013;5965.9105113422465;6063.333033366238;6065.514758819372;6109.266887973472;6119.112728654409;6145.924627519798;6194.659293738888;6196.907046394217;6199;6212.658303793154;5765.855853603525;5879.676617786063;6022.638641681513;6041.51241546196;6083.270007599116 07:50:56 INFO - PROCESS | 1643 | |1;ext.html;472.45195573135163;490.840476845025;492.067532026809;514.1901505129422;516.3855012535083;28387;29590.40819183615;39465.605343946554;39620.80189599055;39652;39969.40045899306;40380;40393.79803100981;40404.19191616166;40411.59588404115;8356.540390278533;9190.126937940884;9386.62453501859;9400.7649808755;9420.387674801115;8432.198941100585;11022.50398732059;11271.492782824793;11281.097512199005;11508.309501429942;9612;11185;11224.551017959313;11293.774124517504;11352.545898164064;11256.887431125666;11330.206885518017;11339.092872570174;11367.97688208057;11377.488013039432;11137.941895519994;11169.994700476997;11222.719432014183;11230.887691123065;11241.606543771011;37868.05329866776;38130;38132.42801357982;38147.42778858319;38249.426258606145;3934.4885164928664;3949.545802232751;3971.250217612978;4035.9192816143513;4153.273177193979;22788.77211227883;25325.493490130095;27696.03063892754;27811.58282625762;28165.43669126606;3082.7379672727843;3197.1687361286;3227.6288226853976;3248.951265731016;3257.625373082102;466732;468782;470211;470905;471454.2854571445;44064;54340;54711.972524533805;54777;54813.177802333;56402.71798641063;56513;56538;56559.43440565542;56577;14518.564443066514;15091.396344146111;15107.54677359681;15109.47116850905;15114.924425377803;10549;10804.621838235624;11732.648020559396;11776.52893884235;11796.52813887452;12088.456019479145;13064.150830196008;14644;14920.179390133648;14994.025388349723;6230.875382492325;6277.309495955441;6343.207099112611;6345.714442850082;6352.4918006559365;649.4187702006772;807.2250639386137;807.6728924785472;816.2123550773414;816.7549735079452;26438.867805660848;28742.42515149727;28777.856110719313;28868.711312887022;29472.705272946994;985.3545927417553;988.5847943863595;1023.9692809215735;1036.0934182590233;1154.5612667186415;14618.415263389557;14628.561143165722;14682.192479413521;14721.705565888837;15077.54767356981;448230;469982.65008674737;565372;566483;567512;55009.724951374985;55026.34920952378;55041.72479137579;55080;55214;1279.8464184297943;1310.2269660899924;1322.8280323557876;1332.9266890320932;1334.6863487080527;27077.59383609208;28125.859370703016;28520.714792851806;28535.85856565714;29224;44755.32867006932;45175.77412112919;45210.09579808451;45287.54712452965;45393.319100212866;370766;399902;431076;452005.739971298;455580;24589.385265367793;36440.453393199656;42652.78673606612;43666.56333436752;43709.56937320727;44459.11081778411;46597.068058639314;48857;50928;51232.74383628058;39506.209875802895;40010.599894001854;40185.177778666744;40366.99082522843;41926.79036604797;39732;40177.59822401738;40966.385504216865;41744.37383439312;41794.164116716885;39063;39378;39988.60011399849;41322.79338603288;42509.36235956525;39709.0072748172;39767.403488948265;39931;41299.58700412957;41340.37989430101;27830.44339113247;30722.69277307288;31657.36685266236;31878.681213188498;32449.675503244664 07:50:56 INFO - PROCESS | 1643 | |2;jquery.html;320.7337909535086;360.46111063959387;367.29662695937276;385.6394271356284;388.2778032858882;18550.536236594075;19578.02109894503;19743.703844442327;19807.603847923034;20028.799712002874;17930;19957.301494447696;20345.186192552315;20541.4864628384;20563.691544626843;4167.604077612637;5484.204790305404;5663.008973429654;5664.150377443375;5708.4291570843;5956;6449.419552240297;6496.740130394778;6510.121133646944;6517.706703198343;5337;6267.373262673742;6333.49332053437;6366.840828979266;6448.677566121699;6160.630362178284;6397.584157029778;6416.294207637156;6465.515086368518;6467.773627923048;5361.115415956362;5507.504324610764;5577.358603760579;5579.8884022319735;5580.135079062752;19516.804831951642;24375.390615234584;24400.145994890092;24425;24686.382840428952;2339.3683705399617;2519.433127546306;2525.671662683858;2528.418463753347;2627.54018046841;9740.707778766648;9760.90239097607;9793.45370203745;10031.849522257173;10195.133413659847;1453.4767483705857;1796.4700413378084;1822.6172503774092;1839.8712090153695;1846.5937493751353;110123;111528.4423577885;112483.43758281237;114818;115328;11081.833772493419;11201.095813711525;11264.493097810619;11275.379854108103;11458.161515892269;14191.574252772434;14194.432222710975;14195.21926294043;14217.36021879018;14223;7964.960175199203;8148.551829649307;8193.590320483952;8245.257926786619;8269.917300827035;7901.802454938557;7979.680812767424;7995;8163.591820408956;8190.83618327642;3708.7033037356946;4314.71954322968;4315.331123675835;4360.324149756792;4415.801288942005;1843.8432733217558;1887.9528011799814;1888.499547619882;1895.5924476237676;1897.354899334233;893.6023469555985;903.1916434790957;908.150878928191;945.4520068415554;948.4925564822925;11620.302781833117;11660.358680272666;11715.648530544097;11957.162998590014;12033.819492707442;901.544719916438;918.1048477734156;922.8800255966815;945.7871978804714;948.6964171465203;6004.909926351108;6067.089936509559;6068.362821903725;6143.631382117087;6147.323794382614;520857;527501;533882.2729499752;537236;544480;64314.67842660757;96394.03605963991;109288;115752;118106.40946795382;899.3524662243175;951.6907005223192;1008.2841182760322;1018.3279035836313;1019.0268293779422;17592;18240.4527864159;18491.352802651843;18706.719399209294;18764;49949;50009.749951250014;50579.494205057475;52209.738951305;52262.47737522728;72662.73372662692;75675;76562.6171869137;76631;78386.2017247844;7426.851462970819;7430.851382972419;7722.227777222233;7742.38060955111;7757.185495522889;8768.035516093352;11544.94227528857;12275.140740148105;12316.938415307866;12604.684882877635;10772.676819695422;11014.1739369546;11057.22599418033;11202.83195752048;11413.029892459233;9781.217502599917;9851.655192068243;10611.41637209961;10894;11023.669289921316;10769;10807.189460790629;10926.071283940953;11055.447227638746;11352;7667.69329226847;7751.9612401939885;7869.134395216636;7937.642806073625;8170.795730106557;5489.533389661764;6123.877522449437;7128.857422851617;9179.403338782959;11213.66359009231 07:50:56 INFO - PROCESS | 1643 | |3;mootools.html;1322.5503328868183;1404.901656884018;1427.1936355958876;1427.7786943023834;1437.3962935567065;6542.934570654292;8295.709650162245;8306.252437280644;8318.126596707349;8331.750047498577;7320.084989376328;7568.886466703004;7580.204078571739;7917.92082079179;8113.797155071112;2275.2491677746343;2319.350581837088;2320.060375547906;2321.0947730385165;2323.117215458132;2303.79265866072;2334.3580515358244;2348.4246359641825;2356.7643235676464;2357.26924653358;1967.557299607584;2049.5286084200643;2229.9331020069426;2300.493891343902;2313.9305820825316;2291.59897018022;2295.5294164696197;2298.7241531016216;2300.493891343902;2303.6199027160496;2224.510607666311;2238.3173132194675;2240.7983281504744;2242.416971587399;2289.862608243494;8282.461639993382;8349.582520873932;8359.665613375457;8364.414490985633;8381.156145647949;4931.408231012301;5092.567131793802;5100.744962751847;5106.9489305106845;5107.719075450848;3184.4745616973173;3308.0572036969347;3373.325334932999;3375.4093033719246;3376.7805092668896;1169.3626973299488;1207.4144040140525;1212.678640160358;1244.2037096258402;1257.9811302830465;10591.417472039035;10608.045275925204;10659.840102398548;10755.677329680122;10763.408012559306;4383.1452866691325;4482.798274077674;4521.8191272349395;4576.58810707038;4637.095766325534;9321.813563728687;9338.766530836783;9345.065493450737;9352.205062569621;9408.153266206074;5093.134167191586;5148.0476111919415;5180.041692286939;5185.481451854785;5196.53231209193;3676.6690997810324;4198.979005104955;4374.256376416017;4653.511381304914;4708.85873423791;3694.6674799267657;3756.8497260109834;3796.183820478555;3833.9616603833606;3859.1124041470057;1263.646179069862;1277.399622177575;1278.086168389606;1291.044626976031;1291.1736488647273;703.4794252253398;711.3206887422499;726.5459088069956;727.2690945599638;759.9506032107895;3877.728559000843;4865.95134048662;5084.669496482717;5338.652987555796;5361.249425080492;456.6141610339258;513.2865317209082;525.8895631917263;530.4615814947754;530.4801294731185;3293.9341213175603;3303.174206448388;3307.131470719036;3363.915902102467;3420.6921377075687;7738.4969976951315;9709.563069661883;9932.304738668368;9944.25418093646;9945;9584.089511496397;9766;9882.456464894356;9904.801903962025;9918.355306905029;7643.885341719879;7727.768166955;7744.57404842739;7750.379969602531;7760.262775036363;4988.850334489971;5057.873553161053;5058.34241548611;5071.898562028812;5194.818181363634;8995.100489950952;9086.772830679285;9087.818243635222;9188.586513607037;9235.815283694423;9270.212031977357;9342.346035777702;9416.858747118662;9423.199028082416;9474.810503790024;6402.967985160044;6602.801915942529;6611.173603299588;6819.147606549182;7000.684969176297;7350.264973502607;7424.703011879465;7437;7439.739609113655;7504.174540800398;6498.415142637185;6777.728890844311;7063.1524217094275;7097.893531597134;7200.099987501562;6907.896381554381;6940.340667636567;6948.756793512202;6993.230744617987;7005.684744186422;6117.571769976059;6267.467265282502;6281.71732272058;6335.176427064636;6336.873262534816;4456.46522417312;4481.798319075715;4534.478534968521;4560.498345181961;4690.226112691368;4374.475062992461;4527.7509736964785;4567.794449249725;4576.130535198302;4672.602828759594 07:50:56 INFO - PROCESS | 1643 | |4;prototype.html;305.8103975535168;357.21234677536034;360.68980676618105;365.3715609152257;369.84466524059894;5161.819336323229;5192.766325515357;5204.959008198366;5250.579953603712;5254.238135470355;4142.71001029928;5074.492550744923;5140.742962851861;5154.690718556889;5222.582193424527;3156.8793078457047;3345.126729031738;3515.7187425005936;3895.4351619015224;4048.797560121997;3390.9660903390895;4067.2475592015426;4079.9592004080023;4098.262312783698;4128.834846606147;3703.555573331204;3908.863189788358;4077.0034024991605;4086.754794712312;4100.8974775630695;3423;3582.6417358264225;3897.57126716061;3915.334393153171;4029.879103626896;3169.431131589855;3619.6199399062984;3623.184783423735;3648.3068217038676;3682.502862113634;4531.365608814768;4862.610991120702;5075;5087.186050231945;5087.440381558025;4228.9154216915795;4441.533638967894;4675.625949923998;4686.250199967989;4715;5253.842384728464;5425.159100339453;5435.0162190800465;5444.346678398578;5457.918131228034;2711.2137480130737;3128.89048883288;3758.0604848787802;3768.623137686237;3769.321522126016;4792.520747925179;5496.670199787985;5645.463680950304;5700.465711259046;5722.227499287585;3195.504696772004;3796.2787070456525;3818.2745278397015;3834.8466061357235;3859.1124041470057;3852.9597008807464;3854.190619969782;3862.2661694278;3875.748076375027;3888.902777430587;4429.357743127229;4499.505054444008;4524.8416305429155;4543.7728113594185;4559.886002849889;4760.428748550196;5180.7150606717;5250.711210883438;5250.894982100413;5314.441983591744;968.6997030920437;1120.5965852293168;1247.0771628994478;1256.6795467156;1272.638277043573;675.7229535890261;773.7988123087925;817.627979269431;820.0405525535139;858.914108589136;4242.5757424257945;4507.842225522091;4913.680610760289;5076.847694569169;5088.949110508922;5583.022970980062;5586.190002449622;5594.3286805582775;5613.698383662679;5649.971750141223;4741.810327586858;4864.343313652612;5110.9488905110475;5138.460461651547;5182.740862956837;2485.1982312823056;2522.41984343602;2707.0931238035087;2779.221817890994;2785.832850029005;4506.6169375602785;4955.975220123948;5144.897102057937;5488.149336852714;5514.972425137848;4744.1934871072;4844.588210002119;5281.524662780292;5488.313960754906;5489.3687225969525;5361.5604899770515;5458.399576046631;5560.972195138998;5564.1931919871395;5570.972145139248;4765.8570242892765;5275.709835959059;5328.520433161034;5351.742340549889;5388.622796404371;4424.362985695313;4429.822807087809;4848.878778030577;5042.823501177441;5278.313819203479;4776.5701086902345;5447.202423200255;5494.708743445367;5499.9100341869835;5512.586556008396;3276.5740453741814;3388.5767977449036;3560.857565697343;3563.1448452371765;3570.625084366104;4462.977685111554;4739.21802902531;5214.739263036909;5340.371186787912;5600.451794433605;5488.313960754906;5499.285092937893;5520.006398848085;5530.364008139116;5534.861628459321;4942.827001054946;5470.015397228378;5485.588580856532;5497.862553436196;5523.619095226193;5054.519820617035;5349.732513374393;5391.757370918396;5395.433479484597;5405.270288511158;4705.976470117627;5217.634765566374;5223.328534868806;5339.519443250126;5503.944960550343;5378.542823859859;5382;5387.249144028128;5402.945970540244;5457.61796674229 07:50:56 INFO - PROCESS | 1643 | |5;yui.html;662.2814246542501;665.5773583774302;698.5633978763273;764.9405573281005;787.4133770341095;11952.64142075738;15464.149471779048;16441.177941102942;17016.4044258451;17226.397076102337;15801.446949356774;16064.518064458056;16125.032498050125;16220.837791622109;16362.754558681629;716.9211386747467;862.0646598440668;866.4974314897345;866.5840396609631;867.9045305016443;670.2325836916727;696.0672698583896;699.8460338725472;834.7454026521908;864.0581765875195;893.5308962794552;916.1662886773038;916.3677062826662;916.9449833010008;917.6650522559252;875.0636818604096;876.1282523888708;878.4114643189047;894.7628878347243;912.1699253679153;515.435598020171;533.9599530035263;645.6771614192903;651.6513665189149;652.5888690125191;14913.254337283093;15396.846031539766;15398.538043858702;15416.22918854064;15427.768583471257;13211.207327560378;13215.867841321657;13353.866461335456;13361.331933403291;13386.464541418332;12780.61658150257;12786.808197877039;12792;12877.484900603964;13019.283939383331;4438.866833994985;4482.103579284156;4485.887852803706;4590.357349970974;4642.558956899089;111132.25309948764;111572;111817.44091279573;114722.42638786753;115463.99999999916;10308.278420510642;10436.165106791439;10548.947255263674;10551.103156231653;10673.573057077785;20387.18451261933;20734.170633174806;20776.68834967477;20862.26982055621;20893.89553052225;7423.888641670379;7465.29079737424;7538.698452061856;7575.734849280248;7718.768436946901;4149.937750933738;4259.701820872509;4269.701120921506;4358.5205627380965;4404.207242696345;2733.58996150579;3406.659334066623;3411.3859505288788;3449.292894956528;3449.4998225257195;618.7524990004034;622.0762168180248;626.8558231606756;628.8082134948793;633.4583930739152;562.9753847996607;574.6638216643311;608.7930103764742;611.929628092775;622.9112697502394;8226.753197404087;9524.714258572254;10215.693529194135;10490.632827850988;10515.263931524873;571.0064487791296;601.6510423954184;621.940915613016;634.0869148426251;639.9488040956712;4171.853985110507;4424.358468022118;4426.756528390905;4439.755813430293;4474.597286244254;86684;94390;104575.95424045814;104855;106587;78152.60923695345;90679.09320906838;90751.54624226836;93884;94505.00246267387;79475;82048.58975705084;82320.58839705763;84455;84598;8404.243618074403;8577.914220857712;8942.195202431813;8985.440628341477;9038.457692538468;41586.168276634904;42699.7865010673;44640.77679611711;45694.086118278115;46931.76534117308;65046.67476662587;65459;67917.66041169762;69289.30710692829;71383.64308178426;12438.751224975631;12663.746725065263;12699.738072872342;12723.363831808187;13246.735065298448;14370.856291436952;14582.708345832812;14635.85364146345;14664.853351466349;14847.331870065656;13293.443426833464;13610.387532561257;13849.930750346184;13923.58229253124;14354.995150339377;13355.53255636048;13699.794503082661;13789.793153102511;13835.308234588028;13842.100263482842;13369.465221391036;13383.866161338263;13551.72896542044;13650;14287.785683214968;8722.607482663168;10831.729206769895;11260.15548833857;11413.086953043903;11653.126015548698;10546.736331591772;11237.438128093727;11371.879773318698;11423.885761142614;11581.478833452345 07:50:56 INFO - PROCESS | 1643 | __end_tp_report 07:50:56 INFO - PROCESS | 1643 | __start_cc_report 07:50:56 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1416 07:50:56 INFO - PROCESS | 1643 | __end_cc_report 07:50:56 INFO - PROCESS | 1643 | __startTimestamp1483458656082__endTimestamp 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | ------- Summary: start ------- 07:50:56 INFO - PROCESS | 1643 | Number of tests: 6 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5734.34 Median:6022.64 stddev:764.33 (12.7%) stddev-sans-first:743.46 07:50:56 INFO - PROCESS | 1643 | Values: 3351.3 3353.8 3367.2 3370.0 3427.7 5618.9 5677.5 5679.9 5701.9 5706.0 5688.5 5720.1 5725.4 5728.3 5729.1 5158.1 5190.4 5202.6 5220.4 5245.1 5232.5 5249.5 5267.7 5272.2 5284.8 5235.4 5265.9 5284.8 5331.1 5362.1 5288.2 5299.2 5300.5 5310.4 5321.8 591.5 5181.9 5201.3 5227.0 5270.1 5686.2 5705.6 5756.1 5774.4 5780.8 6282.5 6288.9 6292.4 6306.8 6310.8 6128.9 6180.0 6214.8 6242.4 6284.9 5853.2 5859.9 5872.8 5896.4 5909.6 6306.4 6309.3 6315.0 6317.2 6328.7 6036.1 6044.1 6103.5 6181.6 6187.7 5225.7 6209.5 6252.7 6279.1 6308.5 6250.4 6256.3 6268.6 6279.0 6284.6 6086.3 6113.1 6197.3 6278.0 6284.5 4403.0 4939.3 4972.5 4982.4 4996.8 3774.6 4407.4 4423.7 4435.0 4441.8 5664.3 5721.6 5727.5 5728.6 5826.9 6248.6 6274.3 6300.2 6304.2 6307.5 5822.9 5829.6 5837.0 5848.0 5890.2 5274.8 5437.0 5440.6 5446.5 5462.8 5970.7 6241.0 6280.3 6300.2 6311.8 6143.0 6297.7 6298.7 6307.4 6310.4 6270.1 6294.2 6330.1 6335.7 6347.9 5992.7 6038.7 6111.8 6135.5 6217.8 6255.5 6281.8 6285.1 6293.1 6299.9 6061.2 6132.0 6133.8 6149.7 6151.3 5977.7 6042.1 6096.1 6098.0 6166.7 5965.9 6063.3 6065.5 6109.3 6119.1 6145.9 6194.7 6196.9 6199.0 6212.7 5765.9 5879.7 6022.6 6041.5 6083.3 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:61842.57 Median:21851.63 stddev:126101.64 (577.1%) stddev-sans-first:126371.21 07:50:56 INFO - PROCESS | 1643 | Values: 472.5 490.8 492.1 514.2 516.4 28387.0 29590.4 39465.6 39620.8 39652.0 39969.4 40380.0 40393.8 40404.2 40411.6 8356.5 9190.1 9386.6 9400.8 9420.4 8432.2 11022.5 11271.5 11281.1 11508.3 9612.0 11185.0 11224.6 11293.8 11352.5 11256.9 11330.2 11339.1 11368.0 11377.5 11137.9 11170.0 11222.7 11230.9 11241.6 37868.1 38130.0 38132.4 38147.4 38249.4 3934.5 3949.5 3971.3 4035.9 4153.3 22788.8 25325.5 27696.0 27811.6 28165.4 3082.7 3197.2 3227.6 3249.0 3257.6 466732.0 468782.0 470211.0 470905.0 471454.3 44064.0 54340.0 54712.0 54777.0 54813.2 56402.7 56513.0 56538.0 56559.4 56577.0 14518.6 15091.4 15107.5 15109.5 15114.9 10549.0 10804.6 11732.6 11776.5 11796.5 12088.5 13064.2 14644.0 14920.2 14994.0 6230.9 6277.3 6343.2 6345.7 6352.5 649.4 807.2 807.7 816.2 816.8 26438.9 28742.4 28777.9 28868.7 29472.7 985.4 988.6 1024.0 1036.1 1154.6 14618.4 14628.6 14682.2 14721.7 15077.5 448230.0 469982.7 565372.0 566483.0 567512.0 55009.7 55026.3 55041.7 55080.0 55214.0 1279.8 1310.2 1322.8 1332.9 1334.7 27077.6 28125.9 28520.7 28535.9 29224.0 44755.3 45175.8 45210.1 45287.5 45393.3 370766.0 399902.0 431076.0 452005.7 455580.0 24589.4 36440.5 42652.8 43666.6 43709.6 44459.1 46597.1 48857.0 50928.0 51232.7 39506.2 40010.6 40185.2 40367.0 41926.8 39732.0 40177.6 40966.4 41744.4 41794.2 39063.0 39378.0 39988.6 41322.8 42509.4 39709.0 39767.4 39931.0 41299.6 41340.4 27830.4 30722.7 31657.4 31878.7 32449.7 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:31521.57 Median:4388.06 stddev:88928.44 (2026.6%) stddev-sans-first:89147.04 07:50:56 INFO - PROCESS | 1643 | Values: 320.7 360.5 367.3 385.6 388.3 18550.5 19578.0 19743.7 19807.6 20028.8 17930.0 19957.3 20345.2 20541.5 20563.7 4167.6 5484.2 5663.0 5664.2 5708.4 5956.0 6449.4 6496.7 6510.1 6517.7 5337.0 6267.4 6333.5 6366.8 6448.7 6160.6 6397.6 6416.3 6465.5 6467.8 5361.1 5507.5 5577.4 5579.9 5580.1 19516.8 24375.4 24400.1 24425.0 24686.4 2339.4 2519.4 2525.7 2528.4 2627.5 9740.7 9760.9 9793.5 10031.8 10195.1 1453.5 1796.5 1822.6 1839.9 1846.6 110123.0 111528.4 112483.4 114818.0 115328.0 11081.8 11201.1 11264.5 11275.4 11458.2 14191.6 14194.4 14195.2 14217.4 14223.0 7965.0 8148.6 8193.6 8245.3 8269.9 7901.8 7979.7 7995.0 8163.6 8190.8 3708.7 4314.7 4315.3 4360.3 4415.8 1843.8 1888.0 1888.5 1895.6 1897.4 893.6 903.2 908.2 945.5 948.5 11620.3 11660.4 11715.6 11957.2 12033.8 901.5 918.1 922.9 945.8 948.7 6004.9 6067.1 6068.4 6143.6 6147.3 520857.0 527501.0 533882.3 537236.0 544480.0 64314.7 96394.0 109288.0 115752.0 118106.4 899.4 951.7 1008.3 1018.3 1019.0 17592.0 18240.5 18491.4 18706.7 18764.0 49949.0 50009.7 50579.5 52209.7 52262.5 72662.7 75675.0 76562.6 76631.0 78386.2 7426.9 7430.9 7722.2 7742.4 7757.2 8768.0 11544.9 12275.1 12316.9 12604.7 10772.7 11014.2 11057.2 11202.8 11413.0 9781.2 9851.7 10611.4 10894.0 11023.7 10769.0 10807.2 10926.1 11055.4 11352.0 7667.7 7752.0 7869.1 7937.6 8170.8 5489.5 6123.9 7128.9 9179.4 11213.7 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5247.26 Median:5096.94 stddev:2931.15 (57.5%) stddev-sans-first:2924.53 07:50:56 INFO - PROCESS | 1643 | Values: 1322.6 1404.9 1427.2 1427.8 1437.4 6542.9 8295.7 8306.3 8318.1 8331.8 7320.1 7568.9 7580.2 7917.9 8113.8 2275.2 2319.4 2320.1 2321.1 2323.1 2303.8 2334.4 2348.4 2356.8 2357.3 1967.6 2049.5 2229.9 2300.5 2313.9 2291.6 2295.5 2298.7 2300.5 2303.6 2224.5 2238.3 2240.8 2242.4 2289.9 8282.5 8349.6 8359.7 8364.4 8381.2 4931.4 5092.6 5100.7 5106.9 5107.7 3184.5 3308.1 3373.3 3375.4 3376.8 1169.4 1207.4 1212.7 1244.2 1258.0 10591.4 10608.0 10659.8 10755.7 10763.4 4383.1 4482.8 4521.8 4576.6 4637.1 9321.8 9338.8 9345.1 9352.2 9408.2 5093.1 5148.0 5180.0 5185.5 5196.5 3676.7 4199.0 4374.3 4653.5 4708.9 3694.7 3756.8 3796.2 3834.0 3859.1 1263.6 1277.4 1278.1 1291.0 1291.2 703.5 711.3 726.5 727.3 760.0 3877.7 4866.0 5084.7 5338.7 5361.2 456.6 513.3 525.9 530.5 530.5 3293.9 3303.2 3307.1 3363.9 3420.7 7738.5 9709.6 9932.3 9944.3 9945.0 9584.1 9766.0 9882.5 9904.8 9918.4 7643.9 7727.8 7744.6 7750.4 7760.3 4988.9 5057.9 5058.3 5071.9 5194.8 8995.1 9086.8 9087.8 9188.6 9235.8 9270.2 9342.3 9416.9 9423.2 9474.8 6403.0 6602.8 6611.2 6819.1 7000.7 7350.3 7424.7 7437.0 7439.7 7504.2 6498.4 6777.7 7063.2 7097.9 7200.1 6907.9 6940.3 6948.8 6993.2 7005.7 6117.6 6267.5 6281.7 6335.2 6336.9 4456.5 4481.8 4534.5 4560.5 4690.2 4374.5 4527.8 4567.8 4576.1 4672.6 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4358.00 Median:4949.40 stddev:1344.17 (27.2%) stddev-sans-first:1313.09 07:50:56 INFO - PROCESS | 1643 | Values: 305.8 357.2 360.7 365.4 369.8 5161.8 5192.8 5205.0 5250.6 5254.2 4142.7 5074.5 5140.7 5154.7 5222.6 3156.9 3345.1 3515.7 3895.4 4048.8 3391.0 4067.2 4080.0 4098.3 4128.8 3703.6 3908.9 4077.0 4086.8 4100.9 3423.0 3582.6 3897.6 3915.3 4029.9 3169.4 3619.6 3623.2 3648.3 3682.5 4531.4 4862.6 5075.0 5087.2 5087.4 4228.9 4441.5 4675.6 4686.3 4715.0 5253.8 5425.2 5435.0 5444.3 5457.9 2711.2 3128.9 3758.1 3768.6 3769.3 4792.5 5496.7 5645.5 5700.5 5722.2 3195.5 3796.3 3818.3 3834.8 3859.1 3853.0 3854.2 3862.3 3875.7 3888.9 4429.4 4499.5 4524.8 4543.8 4559.9 4760.4 5180.7 5250.7 5250.9 5314.4 968.7 1120.6 1247.1 1256.7 1272.6 675.7 773.8 817.6 820.0 858.9 4242.6 4507.8 4913.7 5076.8 5088.9 5583.0 5586.2 5594.3 5613.7 5650.0 4741.8 4864.3 5110.9 5138.5 5182.7 2485.2 2522.4 2707.1 2779.2 2785.8 4506.6 4956.0 5144.9 5488.1 5515.0 4744.2 4844.6 5281.5 5488.3 5489.4 5361.6 5458.4 5561.0 5564.2 5571.0 4765.9 5275.7 5328.5 5351.7 5388.6 4424.4 4429.8 4848.9 5042.8 5278.3 4776.6 5447.2 5494.7 5499.9 5512.6 3276.6 3388.6 3560.9 3563.1 3570.6 4463.0 4739.2 5214.7 5340.4 5600.5 5488.3 5499.3 5520.0 5530.4 5534.9 4942.8 5470.0 5485.6 5497.9 5523.6 5054.5 5349.7 5391.8 5395.4 5405.3 4706.0 5217.6 5223.3 5339.5 5503.9 5378.5 5382.0 5387.2 5402.9 5457.6 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20577.95 Median:23529.15 stddev:29911.17 (127.1%) stddev-sans-first:29957.69 07:50:56 INFO - PROCESS | 1643 | Values: 662.3 665.6 698.6 764.9 787.4 11952.6 15464.1 16441.2 17016.4 17226.4 15801.4 16064.5 16125.0 16220.8 16362.8 716.9 862.1 866.5 866.6 867.9 670.2 696.1 699.8 834.7 864.1 893.5 916.2 916.4 916.9 917.7 875.1 876.1 878.4 894.8 912.2 515.4 534.0 645.7 651.7 652.6 14913.3 15396.8 15398.5 15416.2 15427.8 13211.2 13215.9 13353.9 13361.3 13386.5 12780.6 12786.8 12792.0 12877.5 13019.3 4438.9 4482.1 4485.9 4590.4 4642.6 111132.3 111572.0 111817.4 114722.4 115464.0 10308.3 10436.2 10548.9 10551.1 10673.6 20387.2 20734.2 20776.7 20862.3 20893.9 7423.9 7465.3 7538.7 7575.7 7718.8 4149.9 4259.7 4269.7 4358.5 4404.2 2733.6 3406.7 3411.4 3449.3 3449.5 618.8 622.1 626.9 628.8 633.5 563.0 574.7 608.8 611.9 622.9 8226.8 9524.7 10215.7 10490.6 10515.3 571.0 601.7 621.9 634.1 639.9 4171.9 4424.4 4426.8 4439.8 4474.6 86684.0 94390.0 104576.0 104855.0 106587.0 78152.6 90679.1 90751.5 93884.0 94505.0 79475.0 82048.6 82320.6 84455.0 84598.0 8404.2 8577.9 8942.2 8985.4 9038.5 41586.2 42699.8 44640.8 45694.1 46931.8 65046.7 65459.0 67917.7 69289.3 71383.6 12438.8 12663.7 12699.7 12723.4 13246.7 14370.9 14582.7 14635.9 14664.9 14847.3 13293.4 13610.4 13849.9 13923.6 14355.0 13355.5 13699.8 13789.8 13835.3 13842.1 13369.5 13383.9 13551.7 13650.0 14287.8 8722.6 10831.7 11260.2 11413.1 11653.1 10546.7 11237.4 11371.9 11423.9 11581.5 07:50:56 INFO - PROCESS | 1643 | -------- Summary: end -------- 07:50:56 INFO - PROCESS | 1643 | 07:50:56 INFO - TEST-INFO | 1643: exit 0 07:50:56 INFO - TEST-OK | dromaeo_css | took 1118658ms 07:50:56 INFO - TEST-START | kraken 07:50:56 INFO - operating with platform_type : mac_ 07:50:56 INFO - Initialising browser for kraken test... 07:50:56 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpHWKKwf/profile http://localhost:49242/getInfo.html) 07:50:57 INFO - PROCESS | 1654 | 1483458657208 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:50:58 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:50:58 INFO - PROCESS | 1654 | colorDepth:24 07:50:58 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:50:58 INFO - PROCESS | 1654 | __metrics 07:50:58 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:50:58 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:59 INFO - TEST-INFO | 1654: exit 0 07:50:59 INFO - Browser initialized. 07:50:59 INFO - Running cycle 1/1 for kraken test... 07:50:59 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpHWKKwf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:11 INFO - PROCESS | 1655 | RSS: Main: 234233856 07:51:11 INFO - PROCESS | 1655 | 07:51:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 07:51:15 INFO - PROCESS | 1655 | RSS: Main: 285851648 07:51:15 INFO - PROCESS | 1655 | 07:51:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 07:51:18 INFO - PROCESS | 1655 | RSS: Main: 285474816 07:51:18 INFO - PROCESS | 1655 | 07:51:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 07:51:22 INFO - PROCESS | 1655 | RSS: Main: 378187776 07:51:22 INFO - PROCESS | 1655 | 07:51:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 07:51:25 INFO - PROCESS | 1655 | RSS: Main: 280543232 07:51:25 INFO - PROCESS | 1655 | 07:51:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:51:28 INFO - PROCESS | 1655 | RSS: Main: 285872128 07:51:28 INFO - PROCESS | 1655 | 07:51:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 07:51:31 INFO - PROCESS | 1655 | RSS: Main: 326512640 07:51:31 INFO - PROCESS | 1655 | 07:51:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 07:51:35 INFO - PROCESS | 1655 | RSS: Main: 330399744 07:51:35 INFO - PROCESS | 1655 | 07:51:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 07:51:38 INFO - PROCESS | 1655 | RSS: Main: 324767744 07:51:38 INFO - PROCESS | 1655 | 07:51:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:51:40 INFO - PROCESS | 1655 | RSS: Main: 303849472 07:51:40 INFO - PROCESS | 1655 | 07:51:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:51:43 INFO - PROCESS | 1655 | RSS: Main: 305975296 07:51:43 INFO - PROCESS | 1655 | 07:51:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:51:46 INFO - PROCESS | 1655 | RSS: Main: 306798592 07:51:46 INFO - PROCESS | 1655 | 07:51:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:49 INFO - PROCESS | 1655 | RSS: Main: 321470464 07:51:49 INFO - PROCESS | 1655 | 07:51:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:53 INFO - PROCESS | 1655 | RSS: Main: 315748352 07:51:53 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 07:51:55 INFO - PROCESS | 1655 | RSS: Main: 359534592 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | __start_tp_report 07:51:55 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 07:51:55 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 07:51:55 INFO - PROCESS | 1655 | |i|pagename|runs| 07:51:55 INFO - PROCESS | 1655 | |0;ai-astar;113;112;114;114;114;113;114;113;112;112 07:51:55 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;114;111;143;111;109;111;108;127;107 07:51:55 INFO - PROCESS | 1655 | |2;audio-dft;181;185;186;188;184;183;182;184;185;182 07:51:55 INFO - PROCESS | 1655 | |3;audio-fft;81;61;75;113;84;74;60;58;75;58 07:51:55 INFO - PROCESS | 1655 | |4;audio-oscillator;94;70;87;69;69;69;84;93;100;118 07:51:55 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;86;90;87;89;86;94;120;89;86 07:51:55 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;95;93;95;103;96;95;89;139;98 07:51:55 INFO - PROCESS | 1655 | |7;imaging-desaturate;88;87;85;86;84;88;85;85;88;85 07:51:55 INFO - PROCESS | 1655 | |8;json-parse-financial;56;56;55;55;56;55;56;56;55;55 07:51:55 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;62;73;81;80;76;70;70;73;81;88 07:51:55 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;89;85;86;85;84;85;87;87;87 07:51:55 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;144;159;152;149;130;139;153;149;223;142 07:51:55 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;173;174;176;230;179;171;172;171;172;170 07:51:55 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;67;66;67;67;67;67;66;65;66 07:51:55 INFO - PROCESS | 1655 | __end_tp_report 07:51:55 INFO - PROCESS | 1655 | __start_cc_report 07:51:55 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1999 07:51:55 INFO - PROCESS | 1655 | __end_cc_report 07:51:55 INFO - PROCESS | 1655 | __startTimestamp1483458715456__endTimestamp 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | ------- Summary: start ------- 07:51:55 INFO - PROCESS | 1655 | Number of tests: 14 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.50 stddev:0.88 (0.8%) stddev-sans-first:0.93 07:51:55 INFO - PROCESS | 1655 | Values: 113.0 112.0 114.0 114.0 114.0 113.0 114.0 113.0 112.0 112.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:112.50 stddev:13.93 (12.4%) stddev-sans-first:11.84 07:51:55 INFO - PROCESS | 1655 | Values: 142.0 114.0 111.0 143.0 111.0 109.0 111.0 108.0 127.0 107.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.11 (1.1%) stddev-sans-first:1.94 07:51:55 INFO - PROCESS | 1655 | Values: 181.0 185.0 186.0 188.0 184.0 183.0 182.0 184.0 185.0 182.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:73.90 Median:74.50 stddev:16.83 (22.6%) stddev-sans-first:17.65 07:51:55 INFO - PROCESS | 1655 | Values: 81.0 61.0 75.0 113.0 84.0 74.0 60.0 58.0 75.0 58.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:77.00 stddev:16.52 (21.5%) stddev-sans-first:17.22 07:51:55 INFO - PROCESS | 1655 | Values: 94.0 70.0 87.0 69.0 69.0 69.0 84.0 93.0 100.0 118.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:89.00 stddev:10.28 (11.5%) stddev-sans-first:10.86 07:51:55 INFO - PROCESS | 1655 | Values: 89.0 86.0 90.0 87.0 89.0 86.0 94.0 120.0 89.0 86.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:95.00 stddev:14.26 (15.0%) stddev-sans-first:14.97 07:51:55 INFO - PROCESS | 1655 | Values: 94.0 95.0 93.0 95.0 103.0 96.0 95.0 89.0 139.0 98.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.50 stddev:1.52 (1.8%) stddev-sans-first:1.45 07:51:55 INFO - PROCESS | 1655 | Values: 88.0 87.0 85.0 86.0 84.0 88.0 85.0 85.0 88.0 85.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 07:51:55 INFO - PROCESS | 1655 | Values: 56.0 56.0 55.0 55.0 56.0 55.0 56.0 56.0 55.0 55.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:75.40 Median:78.00 stddev:7.40 (9.5%) stddev-sans-first:6.05 07:51:55 INFO - PROCESS | 1655 | Values: 62.0 73.0 81.0 80.0 76.0 70.0 70.0 73.0 81.0 88.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.50 stddev:1.45 (1.7%) stddev-sans-first:1.54 07:51:55 INFO - PROCESS | 1655 | Values: 86.0 89.0 85.0 86.0 85.0 84.0 85.0 87.0 87.0 87.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:150.50 stddev:25.57 (17.0%) stddev-sans-first:26.87 07:51:55 INFO - PROCESS | 1655 | Values: 144.0 159.0 152.0 149.0 130.0 139.0 153.0 149.0 223.0 142.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:173.50 stddev:18.19 (10.5%) stddev-sans-first:19.17 07:51:55 INFO - PROCESS | 1655 | Values: 173.0 174.0 176.0 230.0 179.0 171.0 172.0 171.0 172.0 170.0 07:51:55 INFO - PROCESS | 1655 | 07:51:55 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.73 07:51:55 INFO - PROCESS | 1655 | Values: 68.0 67.0 66.0 67.0 67.0 67.0 67.0 66.0 65.0 66.0 07:51:55 INFO - PROCESS | 1655 | -------- Summary: end -------- 07:51:55 INFO - PROCESS | 1655 | 07:51:56 INFO - TEST-INFO | 1655: exit 0 07:51:56 INFO - TEST-OK | kraken | took 59681ms 07:51:56 INFO - SUITE-END | took 1178s 07:51:56 INFO - Completed test suite (00:19:38) 07:51:56 INFO - javascript benchmark 07:51:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.3464874349497, 3353.8323083845803, 3367.2255381262316, 3369.9831500842492, 3427.67437093476, 5618.943810561904, 5677.460641239086, 5679.858003549908, 5701.857453563658, 5706.029974904266, 5688.516476099526, 5720.0561907285355, 5725.427457254273, 5728.255326807509, 5729.0833466645345, 5158.097332966744, 5190.377154741436, 5202.6358154928985, 5220.425753167148, 5245.108331583621, 5232.476752324776, 5249.527542521172, 5267.736613169345, 5272.182811664198, 5284.841454756344, 5235.397929238129, 5265.947340526584, 5284.788608455658, 5331.093714068598, 5362.0616392131415, 5288.153895376721, 5299.178627312773, 5300.496452836975, 5310.4424035476495, 5321.787128514855, 591.483502620405, 5181.870453238662, 5201.323827902386, 5226.973865130688, 5270.1040823060175, 5686.232358631573, 5705.6291341063115, 5756.136579513065, 5774.393688662672, 5780.797672081457, 6282.528810339243, 6288.937110628926, 6292.402221788922, 6306.810795676137, 6310.810675679737, 6128.900367973349, 6179.969100154471, 6214.844628884269, 6242.406971337761, 6284.874302513924, 5853.239078919713, 5859.853503662443, 5872.765089396376, 5896.380880007623, 5909.586328956974, 6306.432421082033, 6309.274433440121, 6315.0, 6317.178766760292, 6328.746850125944, 6036.094585812076, 6044.093385992049, 6103.496225665457, 6181.629102253879, 6187.659678717714, 5225.738713064332, 6209.5032397408095, 6252.74989000444, 6279.058141278845, 6308.463780578611, 6250.406211409909, 6256.343083976117, 6268.623882567061, 6278.968605156945, 6284.622922624657, 6086.300075491286, 6113.141862489121, 6197.25632924052, 6278.0, 6284.528660350492, 4403.0, 4939.333190019373, 4972.477889821589, 4982.352294201731, 4996.775145118479, 3774.6036666149657, 4407.382966384709, 4423.734575925455, 4435.046465010038, 4441.800118994653, 5664.348599911063, 5721.57088218377, 5727.455891690282, 5728.627639203439, 5826.94173058264, 6248.593841400295, 6274.309825919146, 6300.243970723543, 6304.211973503312, 6307.463865571386, 5822.854428639318, 5829.562782791308, 5836.9785287574505, 5848.0, 5890.175375447441, 5274.7890084396195, 5437.0, 5440.646357986798, 5446.537044351196, 5462.8361149165585, 5970.701464926823, 6241.0, 6280.2777680566405, 6300.243970723543, 6311.779087731908, 6142.955697531341, 6297.653629050446, 6298.653574053471, 6307.432331090224, 6310.4320611143385, 6270.059491076375, 6294.24469063697, 6330.082138090043, 6335.714892829926, 6347.904781428375, 5992.67040312787, 6038.7282572283475, 6111.77406523465, 6135.509159267337, 6217.844553886189, 6255.468285195628, 6281.811545653638, 6285.057241413824, 6293.087502312231, 6299.937000629935, 6061.212042434456, 6131.96934015327, 6133.785317513865, 6149.723262453111, 6151.26184857802, 5977.7310021050025, 6042.063480160582, 6096.0855871619615, 6098.0, 6166.660833654013, 5965.9105113422465, 6063.333033366238, 6065.514758819372, 6109.266887973472, 6119.112728654409, 6145.924627519798, 6194.659293738888, 6196.907046394217, 6199.0, 6212.658303793154, 5765.855853603525, 5879.676617786063, 6022.638641681513, 6041.51241546196, 6083.270007599116], "value": 5685.957503786101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.45195573135163, 490.840476845025, 492.067532026809, 514.1901505129422, 516.3855012535083, 28387.0, 29590.40819183615, 39465.605343946554, 39620.80189599055, 39652.0, 39969.40045899306, 40380.0, 40393.79803100981, 40404.19191616166, 40411.59588404115, 8356.540390278533, 9190.126937940884, 9386.62453501859, 9400.7649808755, 9420.387674801115, 8432.198941100585, 11022.50398732059, 11271.492782824793, 11281.097512199005, 11508.309501429942, 9612.0, 11185.0, 11224.551017959313, 11293.774124517504, 11352.545898164064, 11256.887431125666, 11330.206885518017, 11339.092872570174, 11367.97688208057, 11377.488013039432, 11137.941895519994, 11169.994700476997, 11222.719432014183, 11230.887691123065, 11241.606543771011, 37868.05329866776, 38130.0, 38132.42801357982, 38147.42778858319, 38249.426258606145, 3934.4885164928664, 3949.545802232751, 3971.250217612978, 4035.9192816143513, 4153.273177193979, 22788.77211227883, 25325.493490130095, 27696.03063892754, 27811.58282625762, 28165.43669126606, 3082.7379672727843, 3197.1687361286, 3227.6288226853976, 3248.951265731016, 3257.625373082102, 466732.0, 468782.0, 470211.0, 470905.0, 471454.2854571445, 44064.0, 54340.0, 54711.972524533805, 54777.0, 54813.177802333, 56402.71798641063, 56513.0, 56538.0, 56559.43440565542, 56577.0, 14518.564443066514, 15091.396344146111, 15107.54677359681, 15109.47116850905, 15114.924425377803, 10549.0, 10804.621838235624, 11732.648020559396, 11776.52893884235, 11796.52813887452, 12088.456019479145, 13064.150830196008, 14644.0, 14920.179390133648, 14994.025388349723, 6230.875382492325, 6277.309495955441, 6343.207099112611, 6345.714442850082, 6352.4918006559365, 649.4187702006772, 807.2250639386137, 807.6728924785472, 816.2123550773414, 816.7549735079452, 26438.867805660848, 28742.42515149727, 28777.856110719313, 28868.711312887022, 29472.705272946994, 985.3545927417553, 988.5847943863595, 1023.9692809215735, 1036.0934182590233, 1154.5612667186415, 14618.415263389557, 14628.561143165722, 14682.192479413521, 14721.705565888837, 15077.54767356981, 448230.0, 469982.65008674737, 565372.0, 566483.0, 567512.0, 55009.724951374985, 55026.34920952378, 55041.72479137579, 55080.0, 55214.0, 1279.8464184297943, 1310.2269660899924, 1322.8280323557876, 1332.9266890320932, 1334.6863487080527, 27077.59383609208, 28125.859370703016, 28520.714792851806, 28535.85856565714, 29224.0, 44755.32867006932, 45175.77412112919, 45210.09579808451, 45287.54712452965, 45393.319100212866, 370766.0, 399902.0, 431076.0, 452005.739971298, 455580.0, 24589.385265367793, 36440.453393199656, 42652.78673606612, 43666.56333436752, 43709.56937320727, 44459.11081778411, 46597.068058639314, 48857.0, 50928.0, 51232.74383628058, 39506.209875802895, 40010.599894001854, 40185.177778666744, 40366.99082522843, 41926.79036604797, 39732.0, 40177.59822401738, 40966.385504216865, 41744.37383439312, 41794.164116716885, 39063.0, 39378.0, 39988.60011399849, 41322.79338603288, 42509.36235956525, 39709.0072748172, 39767.403488948265, 39931.0, 41299.58700412957, 41340.37989430101, 27830.44339113247, 30722.69277307288, 31657.36685266236, 31878.681213188498, 32449.675503244664], "value": 19505.14362742423, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.7337909535086, 360.46111063959387, 367.29662695937276, 385.6394271356284, 388.2778032858882, 18550.536236594075, 19578.02109894503, 19743.703844442327, 19807.603847923034, 20028.799712002874, 17930.0, 19957.301494447696, 20345.186192552315, 20541.4864628384, 20563.691544626843, 4167.604077612637, 5484.204790305404, 5663.008973429654, 5664.150377443375, 5708.4291570843, 5956.0, 6449.419552240297, 6496.740130394778, 6510.121133646944, 6517.706703198343, 5337.0, 6267.373262673742, 6333.49332053437, 6366.840828979266, 6448.677566121699, 6160.630362178284, 6397.584157029778, 6416.294207637156, 6465.515086368518, 6467.773627923048, 5361.115415956362, 5507.504324610764, 5577.358603760579, 5579.8884022319735, 5580.135079062752, 19516.804831951642, 24375.390615234584, 24400.145994890092, 24425.0, 24686.382840428952, 2339.3683705399617, 2519.433127546306, 2525.671662683858, 2528.418463753347, 2627.54018046841, 9740.707778766648, 9760.90239097607, 9793.45370203745, 10031.849522257173, 10195.133413659847, 1453.4767483705857, 1796.4700413378084, 1822.6172503774092, 1839.8712090153695, 1846.5937493751353, 110123.0, 111528.4423577885, 112483.43758281237, 114818.0, 115328.0, 11081.833772493419, 11201.095813711525, 11264.493097810619, 11275.379854108103, 11458.161515892269, 14191.574252772434, 14194.432222710975, 14195.21926294043, 14217.36021879018, 14223.0, 7964.960175199203, 8148.551829649307, 8193.590320483952, 8245.257926786619, 8269.917300827035, 7901.802454938557, 7979.680812767424, 7995.0, 8163.591820408956, 8190.83618327642, 3708.7033037356946, 4314.71954322968, 4315.331123675835, 4360.324149756792, 4415.801288942005, 1843.8432733217558, 1887.9528011799814, 1888.499547619882, 1895.5924476237676, 1897.354899334233, 893.6023469555985, 903.1916434790957, 908.150878928191, 945.4520068415554, 948.4925564822925, 11620.302781833117, 11660.358680272666, 11715.648530544097, 11957.162998590014, 12033.819492707442, 901.544719916438, 918.1048477734156, 922.8800255966815, 945.7871978804714, 948.6964171465203, 6004.909926351108, 6067.089936509559, 6068.362821903725, 6143.631382117087, 6147.323794382614, 520857.0, 527501.0, 533882.2729499752, 537236.0, 544480.0, 64314.67842660757, 96394.03605963991, 109288.0, 115752.0, 118106.40946795382, 899.3524662243175, 951.6907005223192, 1008.2841182760322, 1018.3279035836313, 1019.0268293779422, 17592.0, 18240.4527864159, 18491.352802651843, 18706.719399209294, 18764.0, 49949.0, 50009.749951250014, 50579.494205057475, 52209.738951305, 52262.47737522728, 72662.73372662692, 75675.0, 76562.6171869137, 76631.0, 78386.2017247844, 7426.851462970819, 7430.851382972419, 7722.227777222233, 7742.38060955111, 7757.185495522889, 8768.035516093352, 11544.94227528857, 12275.140740148105, 12316.938415307866, 12604.684882877635, 10772.676819695422, 11014.1739369546, 11057.22599418033, 11202.83195752048, 11413.029892459233, 9781.217502599917, 9851.655192068243, 10611.41637209961, 10894.0, 11023.669289921316, 10769.0, 10807.189460790629, 10926.071283940953, 11055.447227638746, 11352.0, 7667.69329226847, 7751.9612401939885, 7869.134395216636, 7937.642806073625, 8170.795730106557, 5489.533389661764, 6123.877522449437, 7128.857422851617, 9179.403338782959, 11213.66359009231], "value": 8913.806256992724, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.5503328868183, 1404.901656884018, 1427.1936355958876, 1427.7786943023834, 1437.3962935567065, 6542.934570654292, 8295.709650162245, 8306.252437280644, 8318.126596707349, 8331.750047498577, 7320.084989376328, 7568.886466703004, 7580.204078571739, 7917.92082079179, 8113.797155071112, 2275.2491677746343, 2319.350581837088, 2320.060375547906, 2321.0947730385165, 2323.117215458132, 2303.79265866072, 2334.3580515358244, 2348.4246359641825, 2356.7643235676464, 2357.26924653358, 1967.557299607584, 2049.5286084200643, 2229.9331020069426, 2300.493891343902, 2313.9305820825316, 2291.59897018022, 2295.5294164696197, 2298.7241531016216, 2300.493891343902, 2303.6199027160496, 2224.510607666311, 2238.3173132194675, 2240.7983281504744, 2242.416971587399, 2289.862608243494, 8282.461639993382, 8349.582520873932, 8359.665613375457, 8364.414490985633, 8381.156145647949, 4931.408231012301, 5092.567131793802, 5100.744962751847, 5106.9489305106845, 5107.719075450848, 3184.4745616973173, 3308.0572036969347, 3373.325334932999, 3375.4093033719246, 3376.7805092668896, 1169.3626973299488, 1207.4144040140525, 1212.678640160358, 1244.2037096258402, 1257.9811302830465, 10591.417472039035, 10608.045275925204, 10659.840102398548, 10755.677329680122, 10763.408012559306, 4383.1452866691325, 4482.798274077674, 4521.8191272349395, 4576.58810707038, 4637.095766325534, 9321.813563728687, 9338.766530836783, 9345.065493450737, 9352.205062569621, 9408.153266206074, 5093.134167191586, 5148.0476111919415, 5180.041692286939, 5185.481451854785, 5196.53231209193, 3676.6690997810324, 4198.979005104955, 4374.256376416017, 4653.511381304914, 4708.85873423791, 3694.6674799267657, 3756.8497260109834, 3796.183820478555, 3833.9616603833606, 3859.1124041470057, 1263.646179069862, 1277.399622177575, 1278.086168389606, 1291.044626976031, 1291.1736488647273, 703.4794252253398, 711.3206887422499, 726.5459088069956, 727.2690945599638, 759.9506032107895, 3877.728559000843, 4865.95134048662, 5084.669496482717, 5338.652987555796, 5361.249425080492, 456.6141610339258, 513.2865317209082, 525.8895631917263, 530.4615814947754, 530.4801294731185, 3293.9341213175603, 3303.174206448388, 3307.131470719036, 3363.915902102467, 3420.6921377075687, 7738.4969976951315, 9709.563069661883, 9932.304738668368, 9944.25418093646, 9945.0, 9584.089511496397, 9766.0, 9882.456464894356, 9904.801903962025, 9918.355306905029, 7643.885341719879, 7727.768166955, 7744.57404842739, 7750.379969602531, 7760.262775036363, 4988.850334489971, 5057.873553161053, 5058.34241548611, 5071.898562028812, 5194.818181363634, 8995.100489950952, 9086.772830679285, 9087.818243635222, 9188.586513607037, 9235.815283694423, 9270.212031977357, 9342.346035777702, 9416.858747118662, 9423.199028082416, 9474.810503790024, 6402.967985160044, 6602.801915942529, 6611.173603299588, 6819.147606549182, 7000.684969176297, 7350.264973502607, 7424.703011879465, 7437.0, 7439.739609113655, 7504.174540800398, 6498.415142637185, 6777.728890844311, 7063.1524217094275, 7097.893531597134, 7200.099987501562, 6907.896381554381, 6940.340667636567, 6948.756793512202, 6993.230744617987, 7005.684744186422, 6117.571769976059, 6267.467265282502, 6281.71732272058, 6335.176427064636, 6336.873262534816, 4456.46522417312, 4481.798319075715, 4534.478534968521, 4560.498345181961, 4690.226112691368, 4374.475062992461, 4527.7509736964785, 4567.794449249725, 4576.130535198302, 4672.602828759594], "value": 4192.752149450236, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.8103975535168, 357.21234677536034, 360.68980676618105, 365.3715609152257, 369.84466524059894, 5161.819336323229, 5192.766325515357, 5204.959008198366, 5250.579953603712, 5254.238135470355, 4142.71001029928, 5074.492550744923, 5140.742962851861, 5154.690718556889, 5222.582193424527, 3156.8793078457047, 3345.126729031738, 3515.7187425005936, 3895.4351619015224, 4048.797560121997, 3390.9660903390895, 4067.2475592015426, 4079.9592004080023, 4098.262312783698, 4128.834846606147, 3703.555573331204, 3908.863189788358, 4077.0034024991605, 4086.754794712312, 4100.8974775630695, 3423.0, 3582.6417358264225, 3897.57126716061, 3915.334393153171, 4029.879103626896, 3169.431131589855, 3619.6199399062984, 3623.184783423735, 3648.3068217038676, 3682.502862113634, 4531.365608814768, 4862.610991120702, 5075.0, 5087.186050231945, 5087.440381558025, 4228.9154216915795, 4441.533638967894, 4675.625949923998, 4686.250199967989, 4715.0, 5253.842384728464, 5425.159100339453, 5435.0162190800465, 5444.346678398578, 5457.918131228034, 2711.2137480130737, 3128.89048883288, 3758.0604848787802, 3768.623137686237, 3769.321522126016, 4792.520747925179, 5496.670199787985, 5645.463680950304, 5700.465711259046, 5722.227499287585, 3195.504696772004, 3796.2787070456525, 3818.2745278397015, 3834.8466061357235, 3859.1124041470057, 3852.9597008807464, 3854.190619969782, 3862.2661694278, 3875.748076375027, 3888.902777430587, 4429.357743127229, 4499.505054444008, 4524.8416305429155, 4543.7728113594185, 4559.886002849889, 4760.428748550196, 5180.7150606717, 5250.711210883438, 5250.894982100413, 5314.441983591744, 968.6997030920437, 1120.5965852293168, 1247.0771628994478, 1256.6795467156, 1272.638277043573, 675.7229535890261, 773.7988123087925, 817.627979269431, 820.0405525535139, 858.914108589136, 4242.5757424257945, 4507.842225522091, 4913.680610760289, 5076.847694569169, 5088.949110508922, 5583.022970980062, 5586.190002449622, 5594.3286805582775, 5613.698383662679, 5649.971750141223, 4741.810327586858, 4864.343313652612, 5110.9488905110475, 5138.460461651547, 5182.740862956837, 2485.1982312823056, 2522.41984343602, 2707.0931238035087, 2779.221817890994, 2785.832850029005, 4506.6169375602785, 4955.975220123948, 5144.897102057937, 5488.149336852714, 5514.972425137848, 4744.1934871072, 4844.588210002119, 5281.524662780292, 5488.313960754906, 5489.3687225969525, 5361.5604899770515, 5458.399576046631, 5560.972195138998, 5564.1931919871395, 5570.972145139248, 4765.8570242892765, 5275.709835959059, 5328.520433161034, 5351.742340549889, 5388.622796404371, 4424.362985695313, 4429.822807087809, 4848.878778030577, 5042.823501177441, 5278.313819203479, 4776.5701086902345, 5447.202423200255, 5494.708743445367, 5499.9100341869835, 5512.586556008396, 3276.5740453741814, 3388.5767977449036, 3560.857565697343, 3563.1448452371765, 3570.625084366104, 4462.977685111554, 4739.21802902531, 5214.739263036909, 5340.371186787912, 5600.451794433605, 5488.313960754906, 5499.285092937893, 5520.006398848085, 5530.364008139116, 5534.861628459321, 4942.827001054946, 5470.015397228378, 5485.588580856532, 5497.862553436196, 5523.619095226193, 5054.519820617035, 5349.732513374393, 5391.757370918396, 5395.433479484597, 5405.270288511158, 4705.976470117627, 5217.634765566374, 5223.328534868806, 5339.519443250126, 5503.944960550343, 5378.542823859859, 5382.0, 5387.249144028128, 5402.945970540244, 5457.61796674229], "value": 3934.5485991287164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.2814246542501, 665.5773583774302, 698.5633978763273, 764.9405573281005, 787.4133770341095, 11952.64142075738, 15464.149471779048, 16441.177941102942, 17016.4044258451, 17226.397076102337, 15801.446949356774, 16064.518064458056, 16125.032498050125, 16220.837791622109, 16362.754558681629, 716.9211386747467, 862.0646598440668, 866.4974314897345, 866.5840396609631, 867.9045305016443, 670.2325836916727, 696.0672698583896, 699.8460338725472, 834.7454026521908, 864.0581765875195, 893.5308962794552, 916.1662886773038, 916.3677062826662, 916.9449833010008, 917.6650522559252, 875.0636818604096, 876.1282523888708, 878.4114643189047, 894.7628878347243, 912.1699253679153, 515.435598020171, 533.9599530035263, 645.6771614192903, 651.6513665189149, 652.5888690125191, 14913.254337283093, 15396.846031539766, 15398.538043858702, 15416.22918854064, 15427.768583471257, 13211.207327560378, 13215.867841321657, 13353.866461335456, 13361.331933403291, 13386.464541418332, 12780.61658150257, 12786.808197877039, 12792.0, 12877.484900603964, 13019.283939383331, 4438.866833994985, 4482.103579284156, 4485.887852803706, 4590.357349970974, 4642.558956899089, 111132.25309948764, 111572.0, 111817.44091279573, 114722.42638786753, 115463.99999999916, 10308.278420510642, 10436.165106791439, 10548.947255263674, 10551.103156231653, 10673.573057077785, 20387.18451261933, 20734.170633174806, 20776.68834967477, 20862.26982055621, 20893.89553052225, 7423.888641670379, 7465.29079737424, 7538.698452061856, 7575.734849280248, 7718.768436946901, 4149.937750933738, 4259.701820872509, 4269.701120921506, 4358.5205627380965, 4404.207242696345, 2733.58996150579, 3406.659334066623, 3411.3859505288788, 3449.292894956528, 3449.4998225257195, 618.7524990004034, 622.0762168180248, 626.8558231606756, 628.8082134948793, 633.4583930739152, 562.9753847996607, 574.6638216643311, 608.7930103764742, 611.929628092775, 622.9112697502394, 8226.753197404087, 9524.714258572254, 10215.693529194135, 10490.632827850988, 10515.263931524873, 571.0064487791296, 601.6510423954184, 621.940915613016, 634.0869148426251, 639.9488040956712, 4171.853985110507, 4424.358468022118, 4426.756528390905, 4439.755813430293, 4474.597286244254, 86684.0, 94390.0, 104575.95424045814, 104855.0, 106587.0, 78152.60923695345, 90679.09320906838, 90751.54624226836, 93884.0, 94505.00246267387, 79475.0, 82048.58975705084, 82320.58839705763, 84455.0, 84598.0, 8404.243618074403, 8577.914220857712, 8942.195202431813, 8985.440628341477, 9038.457692538468, 41586.168276634904, 42699.7865010673, 44640.77679611711, 45694.086118278115, 46931.76534117308, 65046.67476662587, 65459.0, 67917.66041169762, 69289.30710692829, 71383.64308178426, 12438.751224975631, 12663.746725065263, 12699.738072872342, 12723.363831808187, 13246.735065298448, 14370.856291436952, 14582.708345832812, 14635.85364146345, 14664.853351466349, 14847.331870065656, 13293.443426833464, 13610.387532561257, 13849.930750346184, 13923.58229253124, 14354.995150339377, 13355.53255636048, 13699.794503082661, 13789.793153102511, 13835.308234588028, 13842.100263482842, 13369.465221391036, 13383.866161338263, 13551.72896542044, 13650.0, 14287.785683214968, 8722.607482663168, 10831.729206769895, 11260.15548833857, 11413.086953043903, 11653.126015548698, 10546.736331591772, 11237.438128093727, 11371.879773318698, 11423.885761142614, 11581.478833452345], "value": 7396.219603020957, "unit": "score"}], "name": "dromaeo_css", "value": 7029.291136406159, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 112.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 111.0, 143.0, 111.0, 109.0, 111.0, 108.0, 127.0, 107.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 186.0, 188.0, 184.0, 183.0, 182.0, 184.0, 185.0, 182.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 75.0, 113.0, 84.0, 74.0, 60.0, 58.0, 75.0, 58.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 70.0, 87.0, 69.0, 69.0, 69.0, 84.0, 93.0, 100.0, 118.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 86.0, 94.0, 120.0, 89.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 93.0, 95.0, 103.0, 96.0, 95.0, 89.0, 139.0, 98.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 85.0, 86.0, 84.0, 88.0, 85.0, 85.0, 88.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0, 55.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 73.0, 81.0, 80.0, 76.0, 70.0, 70.0, 73.0, 81.0, 88.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 86.0, 85.0, 84.0, 85.0, 87.0, 87.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 159.0, 152.0, 149.0, 130.0, 139.0, 153.0, 149.0, 223.0, 142.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 176.0, 230.0, 179.0, 171.0, 172.0, 171.0, 172.0, 170.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 66.0, 65.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1468.3999999999999, "alertThreshold": 2.0}]} 07:51:56 INFO - Return code: 0 07:51:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:56 INFO - # TBPL SUCCESS # 07:51:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:56 INFO - 2017-01-03 07:51:56.779 screenresolution[1659:10143] starting screenresolution argv=screenresolution get 07:51:56 INFO - 2017-01-03 07:51:56.782 screenresolution[1659:10143] Display 0: 1600x1200x32@60 07:51:56 INFO - 2017-01-03 07:51:56.788 screenresolution[1660:10146] starting screenresolution argv=screenresolution list 07:51:56 INFO - Available Modes on Display 0 07:51:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:57 INFO - Intel Iris: 07:51:57 INFO - Chipset Model: Intel Iris 07:51:57 INFO - Type: GPU 07:51:57 INFO - Bus: Built-In 07:51:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:57 INFO - Vendor: Intel (0x8086) 07:51:57 INFO - Device ID: 0x0a2e 07:51:57 INFO - Revision ID: 0x0009 07:51:57 INFO - Displays: 07:51:57 INFO - Display: 07:51:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:57 INFO - Display Serial Number: 1600x1200 60 07:51:57 INFO - Main Display: Yes 07:51:57 INFO - Mirror: Off 07:51:57 INFO - Online: Yes 07:51:57 INFO - Rotation: Supported 07:51:57 INFO - Return code: 0 07:51:57 INFO - Running post-action listener: _resource_record_post_action 07:51:57 INFO - [mozharness: 2017-01-03 15:51:57.025850Z] Finished run-tests step (success) 07:51:57 INFO - Running post-run listener: _resource_record_post_run 07:51:57 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37196800; Write bytes: 550757888; Read time: 380; Write time: 1676 07:51:57 INFO - TinderboxPrint: CPU usage
53.6% 07:51:57 INFO - TinderboxPrint: I/O read bytes / time
37,196,800 / 380 07:51:57 INFO - TinderboxPrint: I/O write bytes / time
550,757,888 / 1,676 07:51:57 INFO - TinderboxPrint: CPU idle
2,229.2 (46.4%) 07:51:57 INFO - TinderboxPrint: CPU system
960.8 (20.0%) 07:51:57 INFO - TinderboxPrint: CPU user
1,615.5 (33.6%) 07:51:57 INFO - TinderboxPrint: Swap in / out
286,285,824 / 0 07:51:57 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250526208; Write bytes: 251840512; Read time: 18377; Write time: 531 07:51:57 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34440192; Write bytes: 297426432; Read time: 352; Write time: 1139 07:51:57 INFO - Running post-run listener: _upload_blobber_files 07:51:57 INFO - Blob upload gear active. 07:51:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:57 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 07:51:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:57 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:51:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:58 INFO - (blobuploader) - INFO - Done attempting. 07:51:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:51:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:59 INFO - (blobuploader) - INFO - Done attempting. 07:51:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:51:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:52:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:00 INFO - (blobuploader) - INFO - Done attempting. 07:52:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:52:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:02 INFO - (blobuploader) - INFO - Done attempting. 07:52:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:52:02 INFO - Return code: 0 07:52:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:52:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:52:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a8e8d2ee46ab23b774d9c9e66caeae671f62484b057b59f288a13a42ff7ce5649c8bd5bc85453b4efa779465f218e53b0c269604895b41cfcbb11364a9b5d36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a8fc3952889e2d182e025f2da5131cee73af5795a46a126392d6aabaeaa54156c1017dac2a30b52810f3bfed6a4565ed4d079d8a0e5f79eb6c2d4c822db09b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef6ebd5d90a2f4aedf58cd7a070830fa937650b615932c4bcaf120fcf6c7b846ce9efab57cdd04c26c58fa39be8257cadee489db39e4616f6a24e7859edfcaa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe6d7969d3bde5b4704817f0566d4f36b7dd3a15a1e76824f8aaee256118443d032c5e2f09ba9d85d812f5a61567699e9eb0ded5a22b182e706dfface93b923a"} 07:52:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:52:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:52:02 INFO - Contents: 07:52:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a8e8d2ee46ab23b774d9c9e66caeae671f62484b057b59f288a13a42ff7ce5649c8bd5bc85453b4efa779465f218e53b0c269604895b41cfcbb11364a9b5d36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a8fc3952889e2d182e025f2da5131cee73af5795a46a126392d6aabaeaa54156c1017dac2a30b52810f3bfed6a4565ed4d079d8a0e5f79eb6c2d4c822db09b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef6ebd5d90a2f4aedf58cd7a070830fa937650b615932c4bcaf120fcf6c7b846ce9efab57cdd04c26c58fa39be8257cadee489db39e4616f6a24e7859edfcaa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe6d7969d3bde5b4704817f0566d4f36b7dd3a15a1e76824f8aaee256118443d032c5e2f09ba9d85d812f5a61567699e9eb0ded5a22b182e706dfface93b923a"} 07:52:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:52:02 INFO - Copying logs to upload dir... 07:52:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:52:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.068360 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-03 07:52:02.815817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 07:52:02.819088) ========= 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.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a8e8d2ee46ab23b774d9c9e66caeae671f62484b057b59f288a13a42ff7ce5649c8bd5bc85453b4efa779465f218e53b0c269604895b41cfcbb11364a9b5d36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a8fc3952889e2d182e025f2da5131cee73af5795a46a126392d6aabaeaa54156c1017dac2a30b52810f3bfed6a4565ed4d079d8a0e5f79eb6c2d4c822db09b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef6ebd5d90a2f4aedf58cd7a070830fa937650b615932c4bcaf120fcf6c7b846ce9efab57cdd04c26c58fa39be8257cadee489db39e4616f6a24e7859edfcaa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe6d7969d3bde5b4704817f0566d4f36b7dd3a15a1e76824f8aaee256118443d032c5e2f09ba9d85d812f5a61567699e9eb0ded5a22b182e706dfface93b923a"} build_url:https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/YHRKaJiPQe-Rijxybtpb7g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a8e8d2ee46ab23b774d9c9e66caeae671f62484b057b59f288a13a42ff7ce5649c8bd5bc85453b4efa779465f218e53b0c269604895b41cfcbb11364a9b5d36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a8fc3952889e2d182e025f2da5131cee73af5795a46a126392d6aabaeaa54156c1017dac2a30b52810f3bfed6a4565ed4d079d8a0e5f79eb6c2d4c822db09b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef6ebd5d90a2f4aedf58cd7a070830fa937650b615932c4bcaf120fcf6c7b846ce9efab57cdd04c26c58fa39be8257cadee489db39e4616f6a24e7859edfcaa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe6d7969d3bde5b4704817f0566d4f36b7dd3a15a1e76824f8aaee256118443d032c5e2f09ba9d85d812f5a61567699e9eb0ded5a22b182e706dfface93b923a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 07:52:02.854372) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:52:02.854872) ========= 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.vCg96v2caG/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.62uIgPA3dw/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.005124 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:52:02.903749) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 07:52:02.904068) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 07:52:02.904398) ========= ========= Total master_lag: 0.18 =========