builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0001 starttime: 1491329034.94 results: success (0) buildid: 20170404094027 builduid: ae5f23ae258b4ed0b5e777400344aa91 revision: e99180e4d04608f53182e8d55e981ae3a3d27050 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:54.939969) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:54.940438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:54.940771) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:54.969102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:54.969487) ========= 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.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-04-04 11:03:54-- 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% 39.8M=0s 2017-04-04 11:03:55 (39.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110761 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:55.104758) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:55.105095) ========= 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.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.035976 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:55.163506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-04 11:03:55.163897) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e99180e4d04608f53182e8d55e981ae3a3d27050 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e99180e4d04608f53182e8d55e981ae3a3d27050 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-04-04 11:03:55,269 truncating revision to first 12 chars 2017-04-04 11:03:55,269 Setting DEBUG logging. 2017-04-04 11:03:55,269 attempt 1/10 2017-04-04 11:03:55,269 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e99180e4d046?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 11:03:56,343 unpacking tar archive at: mozilla-aurora-e99180e4d046/testing/mozharness/ program finished with exit code 0 elapsedTime=2.263456 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-04 11:03:57.751591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:57.751930) ========= script_repo_revision: e99180e4d04608f53182e8d55e981ae3a3d27050 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:57.752318) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:57.752585) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 11:03:57.773268) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-04 11:03:57.773636) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:03:57 INFO - MultiFileLogger online at 20170404 11:03:57 in /builds/slave/test 11:03:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:03:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:03:57 INFO - {'append_to_log': False, 11:03:57 INFO - 'base_work_dir': '/builds/slave/test', 11:03:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:03:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:03:57 INFO - 'branch': 'Mozilla-Aurora', 11:03:57 INFO - 'buildbot_json_path': 'buildprops.json', 11:03:57 INFO - 'config_files': ('talos/mac_config.py',), 11:03:57 INFO - 'default_actions': ('clobber', 11:03:57 INFO - 'read-buildbot-config', 11:03:57 INFO - 'download-and-extract', 11:03:57 INFO - 'populate-webroot', 11:03:57 INFO - 'create-virtualenv', 11:03:57 INFO - 'install', 11:03:57 INFO - 'run-tests'), 11:03:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:03:57 INFO - 'download_minidump_stackwalk': True, 11:03:57 INFO - 'download_symbols': 'ondemand', 11:03:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:03:57 INFO - 'tooltool.py': '/tools/tooltool.py', 11:03:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:03:57 INFO - '/tools/misc-python/virtualenv.py')}, 11:03:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:03:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:03:57 INFO - 'gecko_profile': False, 11:03:57 INFO - 'gecko_profile_interval': 0, 11:03:57 INFO - 'installer_path': 'installer.exe', 11:03:57 INFO - 'log_level': 'info', 11:03:57 INFO - 'log_name': 'talos', 11:03:57 INFO - 'log_to_console': True, 11:03:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:03:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:03:57 INFO - 'opt_config_files': (), 11:03:57 INFO - 'pip_index': False, 11:03:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:03:57 INFO - 'cmd': ('bash', 11:03:57 INFO - '-c', 11:03:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:03:57 INFO - 'enabled': True, 11:03:57 INFO - 'halt_on_failure': False, 11:03:57 INFO - 'name': 'check_screen_resolution'},), 11:03:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:03:57 INFO - 'cmd': ('bash', 11:03:57 INFO - '-c', 11:03:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:03:57 INFO - 'enabled': True, 11:03:57 INFO - 'halt_on_failure': False, 11:03:57 INFO - 'name': 'check_screen_resolution'},), 11:03:57 INFO - 'run_cmd_checks_enabled': True, 11:03:57 INFO - 'suite': 'dromaeojs-e10s', 11:03:57 INFO - 'system_bits': '32', 11:03:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:03:57 INFO - 'title': 't-yosemite-r7-0001', 11:03:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:03:57 INFO - 'use_talos_json': True, 11:03:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:03:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:03:57 INFO - 'work_dir': 'build'} 11:03:57 INFO - [mozharness: 2017-04-04 18:03:57.991742Z] Running clobber step. 11:03:57 INFO - Running pre-action listener: _resource_record_pre_action 11:03:57 INFO - Running main action method: clobber 11:03:57 INFO - rmtree: /builds/slave/test/build 11:03:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:00 INFO - Running post-action listener: _resource_record_post_action 11:04:00 INFO - [mozharness: 2017-04-04 18:04:00.434931Z] Finished clobber step (success) 11:04:00 INFO - [mozharness: 2017-04-04 18:04:00.435057Z] Running read-buildbot-config step. 11:04:00 INFO - Running pre-action listener: _resource_record_pre_action 11:04:00 INFO - Running main action method: read_buildbot_config 11:04:00 INFO - Using buildbot properties: 11:04:00 INFO - { 11:04:00 INFO - "project": "", 11:04:00 INFO - "product": "firefox", 11:04:00 INFO - "script_repo_revision": "production", 11:04:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:04:00 INFO - "repository": "", 11:04:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:04:00 INFO - "buildid": "20170404094027", 11:04:00 INFO - "pgo_build": "False", 11:04:00 INFO - "basedir": "/builds/slave/test", 11:04:00 INFO - "buildnumber": 18, 11:04:00 INFO - "slavename": "t-yosemite-r7-0001", 11:04:00 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:04:00 INFO - "platform": "yosemite_r7", 11:04:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:04:00 INFO - "branch": "mozilla-aurora", 11:04:00 INFO - "repo_path": "releases/mozilla-aurora", 11:04:00 INFO - "revision": "e99180e4d04608f53182e8d55e981ae3a3d27050", 11:04:00 INFO - "stage_platform": "macosx64", 11:04:00 INFO - "builduid": "ae5f23ae258b4ed0b5e777400344aa91", 11:04:00 INFO - "slavebuilddir": "test" 11:04:00 INFO - } 11:04:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 11:04:00 INFO - Running post-action listener: _resource_record_post_action 11:04:00 INFO - [mozharness: 2017-04-04 18:04:00.438382Z] Finished read-buildbot-config step (success) 11:04:00 INFO - [mozharness: 2017-04-04 18:04:00.438507Z] Running download-and-extract step. 11:04:00 INFO - Running pre-action listener: _resource_record_pre_action 11:04:00 INFO - Running main action method: download_and_extract 11:04:00 INFO - mkdir: /builds/slave/test/build/tests 11:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:00 INFO - trying https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 11:04:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:04:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 11:04:01 INFO - Downloaded 1199 bytes. 11:04:01 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:04:01 INFO - Using the following test package requirements: 11:04:01 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:04:01 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 11:04:01 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 11:04:01 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'jsshell-mac.zip'], 11:04:01 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 11:04:01 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:04:01 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 11:04:01 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 11:04:01 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 11:04:01 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:04:01 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 11:04:01 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:04:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:04:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:04:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:04:03 INFO - Expected file size: 51790817 11:04:03 INFO - Obtained file size: 51790817 11:04:19 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:04:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:04:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:04:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:04:21 INFO - Expected file size: 51790817 11:04:21 INFO - Obtained file size: 51790817 11:04:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 11:04:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:04:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 11:04:37 INFO - Expected file size: 14404438 11:04:37 INFO - Obtained file size: 14404438 11:04:38 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')]} 11:04:38 INFO - trying https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:04:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 11:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 11:04:40 INFO - Downloaded 74677798 bytes. 11:04:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:04:40 INFO - mkdir: /builds/slave/test/properties 11:04:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:04:40 INFO - Writing to file /builds/slave/test/properties/build_url 11:04:40 INFO - Contents: 11:04:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:04:40 INFO - Running post-action listener: _resource_record_post_action 11:04:40 INFO - Running post-action listener: set_extra_try_arguments 11:04:40 INFO - [mozharness: 2017-04-04 18:04:40.889274Z] Finished download-and-extract step (success) 11:04:40 INFO - [mozharness: 2017-04-04 18:04:40.889400Z] Running populate-webroot step. 11:04:40 INFO - Running pre-action listener: _resource_record_pre_action 11:04:40 INFO - Running main action method: populate_webroot 11:04:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:04:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:04:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:04:40 INFO - '--tppagecycles', 11:04:40 INFO - '7'], 11:04:40 INFO - 'tests': ['tsvgm']}}, 11:04:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:04:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:04:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:04:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:04:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['damp', 'tps']}, 11:04:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'tests': ['damp', 'tps']}, 11:04:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['dromaeo_dom']}, 11:04:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:04:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['basic_compositor_video']}, 11:04:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:04:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'other-e10s': {'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['a11yr', 11:04:40 INFO - 'ts_paint', 11:04:40 INFO - 'tpaint', 11:04:40 INFO - 'sessionrestore', 11:04:40 INFO - 'sessionrestore_no_auto_restore', 11:04:40 INFO - 'tabpaint']}, 11:04:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['tsvgx', 11:04:40 INFO - 'tsvgr_opacity', 11:04:40 INFO - 'tart', 11:04:40 INFO - 'tscrollx', 11:04:40 INFO - 'cart', 11:04:40 INFO - 'tsvg_static']}, 11:04:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:04:40 INFO - 'tsvgr_opacity', 11:04:40 INFO - 'tart', 11:04:40 INFO - 'tscrollx', 11:04:40 INFO - 'cart', 11:04:40 INFO - 'tsvg_static']}, 11:04:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'talos_options': ['--disable-e10s'], 11:04:40 INFO - 'tests': ['tp5o']}, 11:04:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:04:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'talos_options': ['--disable-e10s', 11:04:40 INFO - '--xperf_path', 11:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:40 INFO - 'tests': ['tp5n']}, 11:04:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:04:40 INFO - 'talos_options': ['--xperf_path', 11:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:40 INFO - 'tests': ['tp5n']}}, 11:04:40 INFO - 'talos.zip': {'path': '', 11:04:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:04:40 INFO - Running post-action listener: _resource_record_post_action 11:04:40 INFO - [mozharness: 2017-04-04 18:04:40.907876Z] Finished populate-webroot step (success) 11:04:40 INFO - [mozharness: 2017-04-04 18:04:40.907996Z] Running create-virtualenv step. 11:04:40 INFO - Running pre-action listener: _resource_record_pre_action 11:04:40 INFO - Running main action method: create_virtualenv 11:04:40 INFO - Creating virtualenv /builds/slave/test/build/venv 11:04:40 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 11:04:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:04:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:04:41 INFO - Using real prefix '/tools/python27' 11:04:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:04:41 INFO - Installing distribute.............................................................................................................................................................................................done. 11:04:44 INFO - Installing pip.................done. 11:04:44 INFO - Return code: 0 11:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:04:44 INFO - Reading from file tmpfile_stdout 11:04:44 INFO - Output received: 11:04:44 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) 11:04:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:04: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')]} 11:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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')]} 11:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:44 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:44 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 11:04:44 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 11:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:44 INFO - 'HOME': '/Users/cltbld', 11:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:44 INFO - 'LOGNAME': 'cltbld', 11:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:44 INFO - 'MOZ_NO_REMOTE': '1', 11:04:44 INFO - 'NO_EM_RESTART': '1', 11:04:44 INFO - 'PAGER': '/bin/cat', 11:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:44 INFO - 'PWD': '/builds/slave/test', 11:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:44 INFO - 'SHELL': '/bin/bash', 11:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:44 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:44 INFO - 'USER': 'cltbld', 11:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:44 INFO - 'XPC_FLAGS': '0x0', 11:04:44 INFO - 'XPC_SERVICE_NAME': '0', 11:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:45 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 11:04:45 INFO - Cleaning up... 11:04:45 INFO - Return code: 0 11:04:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:45 INFO - 'HOME': '/Users/cltbld', 11:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:45 INFO - 'LOGNAME': 'cltbld', 11:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:45 INFO - 'MOZ_NO_REMOTE': '1', 11:04:45 INFO - 'NO_EM_RESTART': '1', 11:04:45 INFO - 'PAGER': '/bin/cat', 11:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:45 INFO - 'PWD': '/builds/slave/test', 11:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:45 INFO - 'SHELL': '/bin/bash', 11:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:45 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:45 INFO - 'USER': 'cltbld', 11:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:45 INFO - 'XPC_FLAGS': '0x0', 11:04:45 INFO - 'XPC_SERVICE_NAME': '0', 11:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:45 INFO - Downloading/unpacking psutil>=3.1.1 11:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:04:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:04:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:04:48 INFO - Installing collected packages: psutil 11:04:48 INFO - Running setup.py install for psutil 11:04:49 INFO - building 'psutil._psutil_osx' extension 11:04:49 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 11:04:49 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 11:04:49 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 11:04:49 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 11:04:49 INFO - building 'psutil._psutil_posix' extension 11:04:49 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 11:04:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:04:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:04:49 INFO - ^ 11:04:49 INFO - 1 warning generated. 11:04:49 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 11:04:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:04:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:04:50 INFO - Successfully installed psutil 11:04:50 INFO - Cleaning up... 11:04:50 INFO - Return code: 0 11:04:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:50 INFO - 'HOME': '/Users/cltbld', 11:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:50 INFO - 'LOGNAME': 'cltbld', 11:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:50 INFO - 'MOZ_NO_REMOTE': '1', 11:04:50 INFO - 'NO_EM_RESTART': '1', 11:04:50 INFO - 'PAGER': '/bin/cat', 11:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:50 INFO - 'PWD': '/builds/slave/test', 11:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:50 INFO - 'SHELL': '/bin/bash', 11:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:50 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:50 INFO - 'USER': 'cltbld', 11:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:50 INFO - 'XPC_FLAGS': '0x0', 11:04:50 INFO - 'XPC_SERVICE_NAME': '0', 11:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:04:53 INFO - Installing collected packages: mozsystemmonitor 11:04:53 INFO - Running setup.py install for mozsystemmonitor 11:04:53 INFO - Successfully installed mozsystemmonitor 11:04:53 INFO - Cleaning up... 11:04:53 INFO - Return code: 0 11:04:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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')]} 11:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:54 INFO - 'HOME': '/Users/cltbld', 11:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:54 INFO - 'LOGNAME': 'cltbld', 11:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:54 INFO - 'MOZ_NO_REMOTE': '1', 11:04:54 INFO - 'NO_EM_RESTART': '1', 11:04:54 INFO - 'PAGER': '/bin/cat', 11:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:54 INFO - 'PWD': '/builds/slave/test', 11:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:54 INFO - 'SHELL': '/bin/bash', 11:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:54 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:54 INFO - 'USER': 'cltbld', 11:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:54 INFO - 'XPC_FLAGS': '0x0', 11:04:54 INFO - 'XPC_SERVICE_NAME': '0', 11:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:54 INFO - Downloading/unpacking jsonschema==2.5.1 11:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:04:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:57 INFO - Downloading functools32-3.2.3-2.tar.gz 11:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:04:57 INFO - warning: no files found matching '*.txt' 11:04:57 INFO - no previously-included directories found matching 'build' 11:04:57 INFO - no previously-included directories found matching 'dist' 11:04:57 INFO - no previously-included directories found matching '.git*' 11:04:57 INFO - Installing collected packages: jsonschema, functools32 11:04:57 INFO - Running setup.py install for functools32 11:04:57 INFO - warning: no files found matching '*.txt' 11:04:57 INFO - no previously-included directories found matching 'build' 11:04:57 INFO - no previously-included directories found matching 'dist' 11:04:57 INFO - no previously-included directories found matching '.git*' 11:04:57 INFO - Successfully installed jsonschema functools32 11:04:57 INFO - Cleaning up... 11:04:57 INFO - Return code: 0 11:04:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:04:57 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')]} 11:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 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')]} 11:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:57 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 11:04:57 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 11:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:57 INFO - 'HOME': '/Users/cltbld', 11:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:57 INFO - 'LOGNAME': 'cltbld', 11:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:57 INFO - 'MOZ_NO_REMOTE': '1', 11:04:57 INFO - 'NO_EM_RESTART': '1', 11:04:57 INFO - 'PAGER': '/bin/cat', 11:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:57 INFO - 'PWD': '/builds/slave/test', 11:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:57 INFO - 'SHELL': '/bin/bash', 11:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:57 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:57 INFO - 'USER': 'cltbld', 11:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:57 INFO - 'XPC_FLAGS': '0x0', 11:04:57 INFO - 'XPC_SERVICE_NAME': '0', 11:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:04:58 INFO - Cleaning up... 11:04:58 INFO - Return code: 0 11:04:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:58 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:04:58 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 11:04:58 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 11:04:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:58 INFO - 'HOME': '/Users/cltbld', 11:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:58 INFO - 'LOGNAME': 'cltbld', 11:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:58 INFO - 'MOZ_NO_REMOTE': '1', 11:04:58 INFO - 'NO_EM_RESTART': '1', 11:04:58 INFO - 'PAGER': '/bin/cat', 11:04:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:58 INFO - 'PWD': '/builds/slave/test', 11:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:58 INFO - 'SHELL': '/bin/bash', 11:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:04:58 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:58 INFO - 'USER': 'cltbld', 11:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:58 INFO - 'XPC_FLAGS': '0x0', 11:04:58 INFO - 'XPC_SERVICE_NAME': '0', 11:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:58 INFO - Downloading/unpacking blobuploader==1.2.4 11:04:58 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 11:04:58 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 11:04:58 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 11:04:58 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 11:04:58 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 11:04:58 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 11:05:01 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:02 INFO - Downloading docopt-0.6.1.tar.gz 11:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:02 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:02 INFO - Running setup.py install for blobuploader 11:05:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:02 INFO - Running setup.py install for requests 11:05:03 INFO - Running setup.py install for docopt 11:05:03 INFO - Successfully installed blobuploader requests docopt 11:05:03 INFO - Cleaning up... 11:05:03 INFO - Return code: 0 11:05:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:03 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:05:03 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 11:05:03 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 11:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:03 INFO - 'HOME': '/Users/cltbld', 11:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:03 INFO - 'LOGNAME': 'cltbld', 11:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:03 INFO - 'MOZ_NO_REMOTE': '1', 11:05:03 INFO - 'NO_EM_RESTART': '1', 11:05:03 INFO - 'PAGER': '/bin/cat', 11:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:03 INFO - 'PWD': '/builds/slave/test', 11:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:03 INFO - 'SHELL': '/bin/bash', 11:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:05:03 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:03 INFO - 'USER': 'cltbld', 11:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:03 INFO - 'XPC_FLAGS': '0x0', 11:05:03 INFO - 'XPC_SERVICE_NAME': '0', 11:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:03 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-LNq2yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:03 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-b_A7bQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:03 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-7gb7BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:03 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-77qWId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-gCLnAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-L8zPs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-o76Zao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-5CWWZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-KX9jjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-LF0hj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-9fN1Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-dFn9QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-AKERAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-jAVc9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-pa9EEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-PC1MwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-9obEBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:05 INFO - Running setup.py install for manifestparser 11:05:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:05 INFO - Running setup.py install for mozcrash 11:05:05 INFO - Running setup.py install for mozdebug 11:05:05 INFO - Running setup.py install for mozdevice 11:05:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Running setup.py install for mozfile 11:05:06 INFO - Running setup.py install for mozhttpd 11:05:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Running setup.py install for mozinfo 11:05:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Running setup.py install for mozInstall 11:05:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Running setup.py install for mozleak 11:05:06 INFO - Running setup.py install for mozlog 11:05:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:06 INFO - Running setup.py install for moznetwork 11:05:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:07 INFO - Running setup.py install for mozprocess 11:05:07 INFO - Running setup.py install for mozprofile 11:05:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:07 INFO - Running setup.py install for mozrunner 11:05:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:07 INFO - Running setup.py install for mozscreenshot 11:05:07 INFO - Running setup.py install for moztest 11:05:07 INFO - Running setup.py install for mozversion 11:05:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:08 INFO - Cleaning up... 11:05:08 INFO - Return code: 0 11:05:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:05:08 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 11:05:08 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 11:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:08 INFO - 'HOME': '/Users/cltbld', 11:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:08 INFO - 'LOGNAME': 'cltbld', 11:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:08 INFO - 'MOZ_NO_REMOTE': '1', 11:05:08 INFO - 'NO_EM_RESTART': '1', 11:05:08 INFO - 'PAGER': '/bin/cat', 11:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:08 INFO - 'PWD': '/builds/slave/test', 11:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:08 INFO - 'SHELL': '/bin/bash', 11:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:05:08 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:08 INFO - 'USER': 'cltbld', 11:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:08 INFO - 'XPC_FLAGS': '0x0', 11:05:08 INFO - 'XPC_SERVICE_NAME': '0', 11:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-EHOiO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-eojFT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-4AsxXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-rrPQ9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-DmDCpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-JZ5vtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:08 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)) 11:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:08 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-xZKNIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-aVbhXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-t7IO5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Nm1s9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-orvuDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-EodOtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Lamtso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-lS5TqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-JFpNFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:09 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)) 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-azQYxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:10 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)) 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-6CAzPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:10 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)) 11:05:10 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)) 11:05:10 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)) 11:05:10 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)) 11:05:10 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)) 11:05:10 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)) 11:05:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:10 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 11:05:10 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 11:05:10 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 11:05:10 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 11:05:10 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 11:05:10 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 11:05:12 INFO - Downloading blessings-1.6.tar.gz 11:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:12 INFO - Installing collected packages: blessings 11:05:12 INFO - Running setup.py install for blessings 11:05:13 INFO - Successfully installed blessings 11:05:13 INFO - Cleaning up... 11:05:13 INFO - Return code: 0 11:05:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:13 INFO - Reading from file tmpfile_stdout 11:05:13 INFO - Current package versions: 11:05:13 INFO - blessings == 1.6 11:05:13 INFO - blobuploader == 1.2.4 11:05:13 INFO - docopt == 0.6.1 11:05:13 INFO - functools32 == 3.2.3-2 11:05:13 INFO - jsonschema == 2.5.1 11:05:13 INFO - manifestparser == 1.1 11:05:13 INFO - mozInstall == 1.12 11:05:13 INFO - mozcrash == 1.0 11:05:13 INFO - mozdebug == 0.1 11:05:13 INFO - mozdevice == 0.49 11:05:13 INFO - mozfile == 1.2 11:05:13 INFO - mozhttpd == 0.7 11:05:13 INFO - mozinfo == 0.9 11:05:13 INFO - mozleak == 0.1 11:05:13 INFO - mozlog == 3.4 11:05:13 INFO - moznetwork == 0.27 11:05:13 INFO - mozprocess == 0.25 11:05:13 INFO - mozprofile == 0.28 11:05:13 INFO - mozrunner == 6.13 11:05:13 INFO - mozscreenshot == 0.1 11:05:13 INFO - mozsystemmonitor == 0.3 11:05:13 INFO - moztest == 0.8 11:05:13 INFO - mozversion == 1.4 11:05:13 INFO - psutil == 3.1.1 11:05:13 INFO - requests == 1.2.3 11:05:13 INFO - wsgiref == 0.1.2 11:05:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:13 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')]} 11:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:13 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')]} 11:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:13 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:05:13 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 11:05:13 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 11:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:13 INFO - 'HOME': '/Users/cltbld', 11:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:13 INFO - 'LOGNAME': 'cltbld', 11:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:13 INFO - 'MOZ_NO_REMOTE': '1', 11:05:13 INFO - 'NO_EM_RESTART': '1', 11:05:13 INFO - 'PAGER': '/bin/cat', 11:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:13 INFO - 'PWD': '/builds/slave/test', 11:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:13 INFO - 'SHELL': '/bin/bash', 11:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:05:13 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:13 INFO - 'USER': 'cltbld', 11:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:13 INFO - 'XPC_FLAGS': '0x0', 11:05:13 INFO - 'XPC_SERVICE_NAME': '0', 11:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 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)) 11:05:13 INFO - Cleaning up... 11:05:13 INFO - Return code: 0 11:05:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:13 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')]} 11:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:13 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')]} 11:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:13 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 0x10da2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4e2db0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, '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.iUkhs03RJg/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.st19nk8Vsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 11:05:13 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 11:05:13 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 11:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:13 INFO - 'HOME': '/Users/cltbld', 11:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:13 INFO - 'LOGNAME': 'cltbld', 11:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:13 INFO - 'MOZ_NO_REMOTE': '1', 11:05:13 INFO - 'NO_EM_RESTART': '1', 11:05:13 INFO - 'PAGER': '/bin/cat', 11:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:13 INFO - 'PWD': '/builds/slave/test', 11:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:13 INFO - 'SHELL': '/bin/bash', 11:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:05:13 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:13 INFO - 'USER': 'cltbld', 11:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:13 INFO - 'XPC_FLAGS': '0x0', 11:05:13 INFO - 'XPC_SERVICE_NAME': '0', 11:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:05:13 INFO - Cleaning up... 11:05:13 INFO - Return code: 0 11:05:13 INFO - Running post-action listener: _resource_record_post_action 11:05:13 INFO - Running post-action listener: _start_resource_monitoring 11:05:14 INFO - Starting resource monitoring. 11:05:14 INFO - [mozharness: 2017-04-04 18:05:14.006418Z] Finished create-virtualenv step (success) 11:05:14 INFO - [mozharness: 2017-04-04 18:05:14.007462Z] Running install step. 11:05:14 INFO - Running pre-action listener: _resource_record_pre_action 11:05:14 INFO - Running main action method: install 11:05:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:14 INFO - Reading from file tmpfile_stdout 11:05:14 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:14 INFO - Reading from file tmpfile_stdout 11:05:14 INFO - Output received: 11:05:14 INFO - Usage: mozinstall [options] installer 11:05:14 INFO - Options: 11:05:14 INFO - -h, --help show this help message and exit 11:05:14 INFO - -d DEST, --destination=DEST 11:05:14 INFO - Directory to install application into. [default: 11:05:14 INFO - "/builds/slave/test"] 11:05:14 INFO - --app=APP Application being installed. [default: firefox] 11:05:14 INFO - mkdir: /builds/slave/test/build/application 11:05:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:05:34 INFO - Reading from file tmpfile_stdout 11:05:34 INFO - Output received: 11:05:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:05:34 INFO - Running post-action listener: _resource_record_post_action 11:05:34 INFO - [mozharness: 2017-04-04 18:05:34.493528Z] Finished install step (success) 11:05:34 INFO - [mozharness: 2017-04-04 18:05:34.493676Z] Running run-tests step. 11:05:34 INFO - Running pre-action listener: _resource_record_pre_action 11:05:34 INFO - Running main action method: run_tests 11:05:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:05:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:05:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:05:34 INFO - 2017-04-04 11:05:34.508 screenresolution[1623:7346] starting screenresolution argv=screenresolution get 11:05:34 INFO - 2017-04-04 11:05:34.510 screenresolution[1623:7346] Display 0: 1600x1200x32@60 11:05:34 INFO - 2017-04-04 11:05:34.516 screenresolution[1624:7349] starting screenresolution argv=screenresolution list 11:05:34 INFO - Available Modes on Display 0 11:05:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:05:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:05:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:05:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:05:34 INFO - Intel Iris: 11:05:34 INFO - Chipset Model: Intel Iris 11:05:34 INFO - Type: GPU 11:05:34 INFO - Bus: Built-In 11:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 11:05:34 INFO - Vendor: Intel (0x8086) 11:05:34 INFO - Device ID: 0x0a2e 11:05:34 INFO - Revision ID: 0x0009 11:05:34 INFO - Displays: 11:05:34 INFO - Display: 11:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:05:34 INFO - Display Serial Number: 1600x1200 60 11:05:34 INFO - Main Display: Yes 11:05:34 INFO - Mirror: Off 11:05:34 INFO - Online: Yes 11:05:34 INFO - Rotation: Supported 11:05:34 INFO - Return code: 0 11:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:05:34 INFO - Python 2.7.3 11:05:34 INFO - Return code: 0 11:05:34 INFO - grabbing minidump binary from tooltool 11:05: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')]} 11:05:34 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 0x10d80bd30>, '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 0x10d732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d800be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:05:34 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 11:05:34 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 11:05:34 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 11:05:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:05:34 INFO - Return code: 0 11:05:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:05:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:05:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:05:34 INFO - ENV: RUST_BACKTRACE is now 1 11:05:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:05:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iUkhs03RJg/Render', 11:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:34 INFO - 'HOME': '/Users/cltbld', 11:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:34 INFO - 'LOGNAME': 'cltbld', 11:05:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:05:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:34 INFO - 'MOZ_NO_REMOTE': '1', 11:05:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:05:34 INFO - 'NO_EM_RESTART': '1', 11:05:34 INFO - 'PAGER': '/bin/cat', 11:05:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:34 INFO - 'PWD': '/builds/slave/test', 11:05:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:34 INFO - 'RUST_BACKTRACE': '1', 11:05:34 INFO - 'SHELL': '/bin/bash', 11:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.st19nk8Vsp/Listeners', 11:05:34 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 11:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:34 INFO - 'USER': 'cltbld', 11:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:34 INFO - 'XPC_FLAGS': '0x0', 11:05:34 INFO - 'XPC_SERVICE_NAME': '0', 11:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:05:35 INFO - mozversion application_buildid: 20170404094027 11:05:35 INFO - mozversion application_changeset: e99180e4d04608f53182e8d55e981ae3a3d27050 11:05:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:05:35 INFO - mozversion application_name: Firefox 11:05:35 INFO - mozversion application_remotingname: firefox-dev 11:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:05:35 INFO - mozversion application_vendor: Mozilla 11:05:35 INFO - mozversion application_version: 54.0a2 11:05:35 INFO - mozversion platform_buildid: 20170404094027 11:05:35 INFO - mozversion platform_changeset: e99180e4d04608f53182e8d55e981ae3a3d27050 11:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:05:35 INFO - mozversion platform_version: 54.0a2 11:05:35 INFO - using testdate: 1491329135 11:05:35 INFO - actual date: 1491329135 11:05:35 INFO - starting webserver on 'localhost:49243' 11:05:35 INFO - SUITE-START | Running 2 tests 11:05:35 INFO - TEST-START | dromaeo_css 11:05:35 INFO - operating with platform_type : mac_ 11:05:35 INFO - Initialising browser for dromaeo_css test... 11:05:35 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpuSbx8f/profile http://localhost:49243/getInfo.html) 11:05:37 INFO - PROCESS | 1634 | 2017-04-04 11:05:37.830 plugin-container[1636:7529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 11:05:37 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:37 INFO - PROCESS | 1634 | 2017-04-04 11:05:37.834 plugin-container[1636:7529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 11:05:37 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:37 INFO - PROCESS | 1634 | 2017-04-04 11:05:37.834 plugin-container[1636:7529] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:05:37 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 11:05:37 INFO - PROCESS | 1634 | colorDepth:24 11:05:37 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 11:05:37 INFO - PROCESS | 1634 | __metrics 11:05:37 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 11:05:38 INFO - TEST-INFO | 1634: exit 0 11:05:38 INFO - Browser initialized. 11:05:38 INFO - Running cycle 1/1 for dromaeo_css test... 11:05:38 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpuSbx8f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:05:41 INFO - PROCESS | 1637 | 2017-04-04 11:05:41.289 plugin-container[1638:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:05:41 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:41 INFO - PROCESS | 1637 | 2017-04-04 11:05:41.290 plugin-container[1638:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a23, name = 'com.apple.CFPasteboardClient' 11:05:41 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:41 INFO - PROCESS | 1637 | 2017-04-04 11:05:41.290 plugin-container[1638:7662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:05:50 INFO - PROCESS | 1637 | RSS: Main: 220532736 11:05:50 INFO - PROCESS | 1637 | 11:08:54 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 11:08:56 INFO - PROCESS | 1637 | RSS: Main: 244154368 11:08:56 INFO - PROCESS | 1637 | 11:10:39 INFO - PROCESS | 1637 | ************************* 11:10:39 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 11:10:39 INFO - PROCESS | 1637 | 11:10:39 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:10:39 INFO - PROCESS | 1637 | Full stack: 11:10:39 INFO - PROCESS | 1637 | ************************* 11:11:58 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 11:11:59 INFO - PROCESS | 1637 | RSS: Main: 251015168 11:11:59 INFO - PROCESS | 1637 | 11:15:01 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 11:15:02 INFO - PROCESS | 1637 | RSS: Main: 260026368 11:15:02 INFO - PROCESS | 1637 | 11:18:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 11:18:04 INFO - PROCESS | 1637 | RSS: Main: 256679936 11:18:04 INFO - PROCESS | 1637 | 11:21:07 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 11:21:08 INFO - PROCESS | 1637 | RSS: Main: 262168576 11:21:08 INFO - PROCESS | 1637 | 11:24:10 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 11:24:11 INFO - PROCESS | 1637 | RSS: Main: 258314240 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | __start_tp_report 11:24:11 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 11:24:11 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 11:24:11 INFO - PROCESS | 1637 | |i|pagename|runs| 11:24:11 INFO - PROCESS | 1637 | |0;dojo.html;3421.2131209821737;3435.5533780608534;3444.414449543577;3458.1354661334667;3483;5885.676287804169;5896.705164741762;5932.970335148329;5967.224260846084;5977.5815692901515;5903.4686878180955;5989.281286245657;6005.279366476029;6013.278406591205;6021.2473440819895;4613.123506533765;5044.798208071673;5347.491988261109;5376.924615077;5469.206964990074;4472.306792447176;5306.575473962093;5490.3411590609185;5493.505584497394;5536.861578460531;5261.473852614746;5273.709945952971;5442.510174084332;5459.836204913859;5460.208269800876;5312.203169524563;5332.760025798849;5333.946660533403;5348.545373643245;5353.411124776271;5286.550643195333;5319;5319.9202011969855;5360.705161216131;5431.755570999315;5322.467753224685;5575.717584955442;5985.610935289193;6021.608595441282;6028.1861948636815;6096.146539484475;6163;6281.905771413432;6302;6308.990561510136;6241.157443745127;6241.906371404432;6247.656378899159;6250.0312451569625;6284.811455656292;6103.125543059995;6107.358727333566;6116.640127388561;6131.724072416752;6138.204480310026;6481.578697384655;6508.056331831858;6545.21457425112;6558.278589355263;6576.375244351779;5451.83644490658;5634.492895639412;6021.6989150542295;6120.416658367975;6194.101855230966;6253.405926436982;6283.088952102011;6299.716512756937;6342.112104305401;6371.904421433682;6246.063090536456;6291.213598300213;6295.055741638791;6304.8423789404715;6312.368763123651;5388.434214407509;6274.686265686789;6293.590916590407;6303.558750887394;6307.24313082433;4948;4991.925121123186;5015.072211640858;5021.322121513549;5063.291139240509;4553.863384098482;4565.703229290086;4575.359449677047;4583.518730533313;4590.609798167126;6012.428819262163;6065.029595264737;6098.32918378978;6104.877902441926;6116.877662446726;5231.790728370823;6172.043333283348;6352.078948552434;6368;6373.235211774524;5886.999210134288;6011.609245399035;6026.789062382795;6033.7586496540525;6073.696315184223;5416.756245968941;5508.118701007899;5570.08093664549;5641.210230567724;5646.604737668324;6563.507736919845;6591.769288074895;6629.834254143685;6653;6658.101156343929;6144.3717414884195;6365.681715914278;6369.713362898773;6381.904271435839;6386.137871387489;6321.273053598895;6429.774957876639;6453.774117905851;6565.343465653587;6595.637239951849;6310.52671049664;6313.116163736989;6316.368363163647;6318.273398559225;6332.335104814112;5006.249062640633;5851.5663662402985;6160.229971253593;6332.335104813928;6369.840753981188;5070.239464080418;6037.577369584087;6111.144439778345;6148.200733904564;6184.690765461619;5045.7981680732355;6217.658028808278;6302.054691796267;6315.96842015787;6319.431251187415;5957.642541447527;6186.381361863778;6187.628742275478;6209.223847019122;6249.34381889895;5236.39781425141;6138.449706860676;6195.287541932736;6282;6282.43458088756;6040.969795150996;6117.096718007557;6120.08198770188;6131.570790044654;6142.04798256271 11:24:11 INFO - PROCESS | 1637 | |1;ext.html;732.1580182789791;745.4930647159932;831.4138532334703;959.5969692729053;960.2510042167112;29959.400811983774;38835.22329553411;40869.79565102174;40891.38662920051;41029.58970410295;39448.21103577927;39685.603143968554;39711.20577588447;39871;39965.20069598607;7890.921090789076;8048.114707382183;8518.829623407528;10005.449700266483;10686.78626427471;10698.304610200352;10735.78528429431;10743.946280268588;10795.568177272939;10807.67576972692;10744.301620394688;10802.72993175173;10828.350298982046;10839.783204335909;10872.728181795439;8315.376346774015;9158.49628270445;9394.013628569039;10801.945990270038;10828.133749300037;10452.529636166315;10494.370337779681;10526.631567895163;10545.47272636365;10565.260431769779;33933.660663393544;34156.48765268523;34251;34398.656013439795;34439.48340774891;3678.503402040717;3722.664960153599;3734.7572407793677;3739.551253849528;3784.9053773655605;23748.525029499313;23885.92513336904;24367.26898193057;25042.873070711863;25054.997800087975;3079.676633953425;3099.364630250793;3175.2696879717564;3260.543523906655;3276.6068071831533;314309;314427;338877;343414;447001.7649911762;51247.7437612817;51647.48352516502;54101;54163.72918135384;54348.18477722837;47032.294515582296;56699.71650141723;56826.7158664204;57065.71467142638;57118.42881571214;14956.027858189183;14959.626009349635;15106.169160696047;15133.394664213529;15195;12861.678458038437;12878.905293050195;12888;12919.612411627666;12935.223886566648;13601.251931143684;13627.114237574526;13648.317584120952;13714.51999180024;13751.243681597409;4978.0292842896015;6279.7802076928;6297.118403423525;6300.716467758962;6310.337414571496;865.1996902864825;933.0436302789559;941.1153515695224;945.4847108325979;996.2627655534852;27112.864435677697;27600.033998809944;28291.575626365622;28394;28587.71412285892;1050.4275170032197;1051.5898799468214;1054.4569546683465;1086.7989421956963;1092.7978324010085;14181;14361.856381436051;14597.270136493133;14801.333939972727;15134.621634459227;500173;501219;501968;502974;504595;56394.87210255772;56734.71632641811;57030.429695702514;57150.7142464285;57479;1437.3244575049919;1447.182341976801;1480.4448331875547;1484.0353770049553;1488.2930607961132;26610.201693949213;27631.30921726973;27817.860910696127;28107.016254431815;28107.875684972372;48653.75673121612;48785.75607121942;48984.51015489942;49010.75494622504;49169.26246106383;450337;456697.7165114153;458124;458450;459213;30356.392872142875;31052.22369440782;32236.516452253705;32258.516122258654;35727.64272357243;31996;34264;38928.80535597304;40533.98665033398;40759.59240407558;28108.297292567848;28395.858020709766;28569.571456427755;28609.28476788097;29250.561241580966;28408.57387139236;28446;28714.85642571774;29019.85490072536;29167.7083229165;33174;33320;33427;34713;40733.59266407298;39003.609963899995;39012.60987390203;39169.804150979064;40277;40598.1880362397;27238;29727;31828.726850925705;31861.20346991344;31991.520127197644 11:24:11 INFO - PROCESS | 1637 | |2;jquery.html;386.8684647219945;410.2635768795013;410.3414020497102;416.444047196992;427.7390791617113;15867.603309917258;17558.209880555372;20637.587248255026;20770.6884396734;20864.89567552162;21612.027458764354;21827.890860545715;22182.779947102943;22804.657930131063;23096.42258943527;6410.326915673857;6631.403173714365;6679.332066793306;6685.465162786965;6686.063951046857;6549.312322206171;6659.4006539411685;7470.813229669247;7784.338331241823;7898.210178982114;7730.4975176613625;7797.844043119134;7926.8810967835525;7961.880571791427;7967.880481792778;7771.72798952034;7785.8053548661455;7929.881051784228;8022.157673444321;8048.31589314906;7605.163432022473;7766.922330776676;7781.3385862201785;7813.882791758128;7832.0601527817025;28347.86608535656;29113.56329655057;29949.101526954226;30495.69504304973;30698.386032279454;3299.472084466474;3323.5180898769545;3327.434336162858;3352.3127758809387;3385.18755498681;17197.828021719746;17363.739543906853;17542.386016489363;17905.7314140288;17964.820351796578;1906.9321180139166;2002.8297594704322;2009.4775358406773;2036.3505560495898;2067.6071546406133;106657.93342066635;115152;132481;142259.28870355582;156013.50611061894;24892.012958445102;25069.247922562354;25253.62119568208;25634.102806401686;25669.10158144456;28405.71594284072;29581.85209073941;43359.56640433618;43667.34498982518;43834.90412739707;10029.29794914349;10117.747056323504;10223.28437009417;10226.41490568963;10296.897031029745;8509.489430634181;8539.231469167804;8601.483910965362;8663.783405414915;8703.086175951561;9227.677031303872;9318;9335.673251436303;9344.766380840398;9405.858912116324;3562.608113107556;3615.9096022599647;3890.9299942515468;4197.979010104991;4263.637590804754;836.6318819719304;998.1715176403568;1019.9694009179736;1024.933379330356;1032.7314898126542;20219.898900505403;20295.898520507304;20425.38723838287;20866.791332086486;20925;924.5377311344328;928.8699582058517;993.339429279521;1026.7843752811843;1030.386919782743;10234.078932896075;10546.314489558154;10550.366977981346;10553.525091370906;10626.256162068736;748176;752656;761098;766149.1692541501;809300;137649;156888.21555892148;161716;162081;168525.1573742148;1209.8608660004036;1215.6839221802395;1232.7596118756935;1235.4502246500401;1245.6263121063644;15566.455174068853;16592.668146637243;17514.299428023245;17737.201825918124;18315;47385.05229895452;62064.379356205856;62661.686691566254;63513;64931.104133752124;68127;83317;84935.15064849272;85139;86002.13997860192;9839.966803485531;11355.772884542097;11496.310221386744;11857.762844742885;11905.226160299553;10333;12633.305168215837;12786.48854045865;12807.167534110253;12879.742405152032;9300.534973251446;10480.266381353233;11157.27477713946;11337.716557086138;11409.885901141215;10071.295009349275;10372.01465860742;10405.011523905217;10603.257771956198;10747.838782418426;10000.849902261332;10810.567577297135;11108.277961932448;11272.943635281772;11539;10820.242583019428;10906.509207085859;11365.431728413712;11422.771544569228;11768.293902365886;9292.58183381763;11219.775604488028;11260.38067906273;11515.96356327901;11669 11:24:11 INFO - PROCESS | 1637 | |3;mootools.html;1374.1617613255914;1508.9924550377245;1535.616095976006;1567.6316065724552;1571.269359747717;10443.425611591369;10894.346339219652;10985.615503457371;11014.50434730438;11069.280496767704;10962.177836662262;11130.888691113087;11157.051650609708;11168.218224724273;11218.495167717452;2371.7628237176273;2422.842515236504;2427.7329493755674;2429.1983645397063;2430.647556104364;2316.1198744477074;2376.786089251967;2385.057902128658;2387.236084452976;2391.9162829300976;1949.4736421166276;2303.7696230376996;2354.9764502355015;2407.1935901473025;2412.0351859256352;2332.055517515409;2377.2036367816804;2393.066703985447;2395.6765836612008;2407.903683852644;1962.2249211561352;2372.810175185998;2380.666706661069;2381.2618088392655;2383.6305372667266;10903.400312982783;10966.396848173346;10972.177086718528;11011.779764404748;11012.779744405067;5528.806491772809;5875.412458754133;5883.294004719418;5888.234529511179;5902.704864756745;3489.3719130556487;3557.0751604582993;3586.9282614347826;3644.5444319460066;3654.433562797744;1074.6829685242794;1168.5909931523984;1181.4978634080567;1211.1400905357207;1225.40567824605;15151.696966060726;15178.089314641156;15179.316930738143;15320.387184512718;15322.15728134952;4451.198784218807;4702.85891423251;4773.880652983634;4790.4251489820745;4802.991371811959;11420.543178272776;11513.424328783527;11530.711732206762;11538.653840384633;11550.364729939765;5708.514776244066;5759.971200144057;5762.423757624204;5770.2498675171955;5811.622244554175;3938.3107956107565;5071.923921141186;5220.634555581149;5267.367915850046;5324.361076670824;3224.4840825467813;3301.5047742838287;3809.961900381016;3828.885133446001;3899.922001559953;1056.693558867935;1061.341967979842;1068.1614932278123;1073.6993641780314;1073.7852429514128;832.5088197963231;938.863864739609;961.7018724195382;964.5129209749032;1006.4414250091123;6459.386358295955;6492.902606460907;6493.967530162319;6534.477241820643;6711.463082953352;1027.429776474064;1052.6894566103017;1053.2048303530785;1067.882532921378;1068.3322923173016;4087.6321131097748;4205.137946720976;4215.515215750167;4276.2089013532595;4278.657707383401;12970.935145324212;13592.980526460356;13597.728045439233;13601.523946661819;13606.047576669538;13242.686362143215;13359.933200333937;13445.932770336085;13539.172211751484;13668.93165534166;10018.799624007625;10079.092881640687;10251.794964100676;10252.948735256276;10276.486175691185;6473.158489396348;6507.479401647952;6523.739050437929;6621;6635.336466353326;11810.645680629596;12032.037437004843;12184.390780461119;12202.694932626755;12320.630381088582;12353.3823308836;12407.93796031014;12454.626361209179;12641.178323408949;12642.115051946275;7037.612931288828;7162.639098571255;7228.783136505913;8521.573921304034;8546.273566746648;9989.400635961574;10071;10197.23520735933;10203.234757393077;10219.284650074424;8595.742127736177;8622.353323500636;8701.651933922825;8740.912590874264;8884.422512536665;7233.3851622612665;8368.748937531884;8372.832543348977;8398.916010839814;8543.444676096035;7539.396848252017;7767.961160194162;7775.883361749691;7968.920310796818;7974.362051035787;5832.883342333214;5900.822975310747;5939.851503712442;6059.091136329585;6075.119107729266;5804.854878628068;5826.73779679907;5869.941300586939;5891.646501209942;6079.240094988127 11:24:11 INFO - PROCESS | 1637 | |4;prototype.html;303.9133846853647;328.9703926646602;365.8170914542729;384.30057295721787;384.567361718067;4199.601037901402;4207.642350400216;5017.623678224134;5268.525832675058;5472.562195024393;4643.628509719223;5124.128898087325;5311.9468805311935;5320.9467905320935;5356.91964620531;3780.235141409359;3892.3966785148345;4418.403515525411;4445.266531022386;4454.8886277842985;4257.595528424795;4368.410264614284;4430.3133014382665;4472.888177795565;4498.685092043558;3995.641481896155;4303.225419424502;4332.718373305741;4355.412019377391;4390.560943905616;3922.921541569167;4405.339199120125;4408.25059739845;4414.84548040817;4416.381706561084;3520.6831385175456;3536.6816986471085;3666.358387282215;4114.588541145891;4143.067809742814;4532.864014079583;4725.243960966229;4956.752162391866;4973.104841128596;5191.831837836495;4225.704200705952;5065.366829146356;5088.720120393377;5094.363204599425;5106.939982713714;4609.285560738057;5264.394594621591;5303.6398802532885;5411.458854114557;5687.407525892757;4383.123375324948;4595.770211489412;4764.6426518011285;4810.951890481086;4871.782054486378;5300.3639563252655;5720.828375148711;5837.562182836305;6035.223797053361;6153.661548614825;4589.33454649074;4673.509281525391;4680;4978.825741099116;4989.625778066659;4670.318685273299;4723.267893476516;5082.110630639623;5086.516780905808;5119.462456442095;4761.714337128956;4770.451398089194;5079.466656001141;5209.557187639093;5224.764885580158;4580.908381832344;4902.583280421204;5539.25220095282;5593.244911936918;5603.691796951207;1483.6958423523367;1744.3981826269917;1771.654527367151;1780.9376671816424;1782.4474412932032;1273.1857103627294;1342.2751714074486;1448.2975756757965;1455.5706066710345;1473.62422582243;4331.242032644274;4609.746463944515;5093;5241.256662720244;5359.249705041298;5256.763445644955;5540.113581826889;5621.690807005572;5799.768009279582;5894.793682221101;4451.9554804451545;4541.977290113528;4725.39336625549;5126.5898728101665;5376.4085950545405;2647.113217072302;2734;2954.778391620637;3023.349979754363;3049.5819443958667;4709.458412282563;5105.8723531911255;5987.880242395215;6019.819405417845;6034.336223015464;5162.767675454538;5514.338279406497;5672.716364181774;5695.629784064023;5893.734781934823;5323.787048518094;5441.564674826083;5654.293213348332;5964.463198312086;6111.236095488064;5176.41941256137;5249.611426800873;5261.447548007557;5278;5772.161512594078;5244.580433565382;5269.841904742864;5494.395616482104;5678.403767604507;5730.023142015998;5416.8104116355735;5652.632578882524;5764.942350576441;6028.8794224116145;6147.694614519555;4599.170870585805;4999.999999999942;5071.974640126776;5104.566111880532;5104.642675012714;5312.601554883322;5490;5864.32560255559;5876.911846322223;5938.485686150004;4934.5312195341385;5662.971685141548;5673.205751194919;5680.573956953328;5755.510781583444;4844.660873738804;5639.661620302795;5674.829755107354;5690.179142674305;5694.829155125353;4467.441569803775;4628.94966903959;5466.890662186814;5566.77732890696;5568.749406276646;4976.168350370267;5179.6273987394125;5564.8330550083565;5701.144828275792;5755.3381361144;5304.469553044664;5398.946010539844;5470.753816078206;5561.0258358283845;5610.09256852672 11:24:11 INFO - PROCESS | 1637 | |5;yui.html;697.6720941157661;715.8532500837329;766.3179770004697;774.752079334613;846.6359465429865;15118.546443606696;15410.845891541081;15988.12065336406;16112.758308625365;16160.75758863618;13987.930060349683;14457.132572045684;14596.489122880701;14933.178675172861;14973.176475293882;928.8095940332232;954.8233576788282;958.6165533786472;958.8753462049958;962.8205448325815;901.3690416708298;953.1897886796203;959.4627008875018;961.6105477281712;961.8076384723048;971.5773638467253;980.2843923935532;981.7938232971085;983.3018556824663;984.0503913723255;975.4293738163183;977.0913050862695;977.5258999385328;978.5254151736438;978.8482785168275;717.587387252332;721.3039416962604;723.583939234942;725.2203880828079;725.2783480436947;15538.689226215525;16054.43809466663;16062.036277823368;16555;16586.75119873203;12173.817392739116;12287;12497;12554.623361299176;12762;12349.567765128268;12677.619671409873;12746.362681865963;12792.680182995406;12881.291528965903;4379.1679580879845;4395.868123956255;4450.599446049839;4481.731096134243;4660.673752837302;107327;108159;110036.34945475824;110686;112437.87562124437;10475.790484190426;10722.839157412645;10750.892491074988;10764.784704306026;11326;16054;20814.687779683318;21038.78961210399;21240;21333.040013199443;6537.313582073909;6582.407583317525;6623.304553021863;6667.494721152651;6739.191297044386;3753.3806921857586;4150.439690641725;4186.1418409226035;4200.453940987713;4254;2818.3236023354657;3337.6996070353784;3378;3408.829558522064;3418.4359580669407;618.3105836991722;619.3868070610064;626.5645376463406;626.6083697688945;627.7802769030894;487.6464563191658;499.3558309780415;503.6952643650612;515.2528833191886;530.2338121414596;9386.577604007836;9512.048795120432;9561.856572151424;9567.90432095684;9762.219022478184;564.4505831492579;568.3123420661046;573.1173992052193;583.0263460021774;617.561531312773;4616.28447590624;4694.906101877943;4991.50084991498;5028.145215313405;5083.288339632454;60617;95368;97493;97569.5121524402;98152;81709.18290817016;82129;82956.3408731822;86900.13099869047;88920;58191;62996.370036299966;77765.61117194491;79290.01774955672;80092.5995370031;9666.295663234896;9786;9786.266030047633;9797.363171393836;9835.311528192959;46106.31149508098;48699;49321.50678493169;49380.53097345046;49615.255771164186;66077.67844643176;69202.44296748714;69651.30348696448;72044;72209.63895180491;11522.53909843597;12095.81856272139;12298.385080746106;12333.69165770827;12668.936655316664;11108.500117494894;14176.50382236658;14185.432582696576;14763.630909226926;15615.76576351333;13101.475940962637;13789.586312410644;13814.309284535531;13840.723185536031;14094.50669226572;13390.665233369244;13914.443422262995;13995;14075.225862577656;14579.416823327372;12880.806787898002;13701.246431446367;13713.17720936747;13864.514741983981;14109.576712698636;9687.370320929118;9706.369086009387;9851.45816980073;10001.34991225568;10029.398236105857;9183.862242066509;10231.84652230232;10278.280520363503;10287.897121028695;10439.63461278882 11:24:11 INFO - PROCESS | 1637 | __end_tp_report 11:24:11 INFO - PROCESS | 1637 | __start_cc_report 11:24:11 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,413 11:24:11 INFO - PROCESS | 1637 | __end_cc_report 11:24:11 INFO - PROCESS | 1637 | __startTimestamp1491330251023__endTimestamp 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | ------- Summary: start ------- 11:24:11 INFO - PROCESS | 1637 | Number of tests: 6 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5837.33 Median:6103.13 stddev:666.38 (10.9%) stddev-sans-first:640.90 11:24:11 INFO - PROCESS | 1637 | Values: 3421.2 3435.6 3444.4 3458.1 3483.0 5885.7 5896.7 5933.0 5967.2 5977.6 5903.5 5989.3 6005.3 6013.3 6021.2 4613.1 5044.8 5347.5 5376.9 5469.2 4472.3 5306.6 5490.3 5493.5 5536.9 5261.5 5273.7 5442.5 5459.8 5460.2 5312.2 5332.8 5333.9 5348.5 5353.4 5286.6 5319.0 5319.9 5360.7 5431.8 5322.5 5575.7 5985.6 6021.6 6028.2 6096.1 6163.0 6281.9 6302.0 6309.0 6241.2 6241.9 6247.7 6250.0 6284.8 6103.1 6107.4 6116.6 6131.7 6138.2 6481.6 6508.1 6545.2 6558.3 6576.4 5451.8 5634.5 6021.7 6120.4 6194.1 6253.4 6283.1 6299.7 6342.1 6371.9 6246.1 6291.2 6295.1 6304.8 6312.4 5388.4 6274.7 6293.6 6303.6 6307.2 4948.0 4991.9 5015.1 5021.3 5063.3 4553.9 4565.7 4575.4 4583.5 4590.6 6012.4 6065.0 6098.3 6104.9 6116.9 5231.8 6172.0 6352.1 6368.0 6373.2 5887.0 6011.6 6026.8 6033.8 6073.7 5416.8 5508.1 5570.1 5641.2 5646.6 6563.5 6591.8 6629.8 6653.0 6658.1 6144.4 6365.7 6369.7 6381.9 6386.1 6321.3 6429.8 6453.8 6565.3 6595.6 6310.5 6313.1 6316.4 6318.3 6332.3 5006.2 5851.6 6160.2 6332.3 6369.8 5070.2 6037.6 6111.1 6148.2 6184.7 5045.8 6217.7 6302.1 6316.0 6319.4 5957.6 6186.4 6187.6 6209.2 6249.3 5236.4 6138.4 6195.3 6282.0 6282.4 6041.0 6117.1 6120.1 6131.6 6142.0 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:57587.58 Median:3268.58 stddev:117517.55 (3595.4%) stddev-sans-first:117769.69 11:24:11 INFO - PROCESS | 1637 | Values: 732.2 745.5 831.4 959.6 960.3 29959.4 38835.2 40869.8 40891.4 41029.6 39448.2 39685.6 39711.2 39871.0 39965.2 7890.9 8048.1 8518.8 10005.4 10686.8 10698.3 10735.8 10743.9 10795.6 10807.7 10744.3 10802.7 10828.4 10839.8 10872.7 8315.4 9158.5 9394.0 10801.9 10828.1 10452.5 10494.4 10526.6 10545.5 10565.3 33933.7 34156.5 34251.0 34398.7 34439.5 3678.5 3722.7 3734.8 3739.6 3784.9 23748.5 23885.9 24367.3 25042.9 25055.0 3079.7 3099.4 3175.3 3260.5 3276.6 314309.0 314427.0 338877.0 343414.0 447001.8 51247.7 51647.5 54101.0 54163.7 54348.2 47032.3 56699.7 56826.7 57065.7 57118.4 14956.0 14959.6 15106.2 15133.4 15195.0 12861.7 12878.9 12888.0 12919.6 12935.2 13601.3 13627.1 13648.3 13714.5 13751.2 4978.0 6279.8 6297.1 6300.7 6310.3 865.2 933.0 941.1 945.5 996.3 27112.9 27600.0 28291.6 28394.0 28587.7 1050.4 1051.6 1054.5 1086.8 1092.8 14181.0 14361.9 14597.3 14801.3 15134.6 500173.0 501219.0 501968.0 502974.0 504595.0 56394.9 56734.7 57030.4 57150.7 57479.0 1437.3 1447.2 1480.4 1484.0 1488.3 26610.2 27631.3 27817.9 28107.0 28107.9 48653.8 48785.8 48984.5 49010.8 49169.3 450337.0 456697.7 458124.0 458450.0 459213.0 30356.4 31052.2 32236.5 32258.5 35727.6 31996.0 34264.0 38928.8 40534.0 40759.6 28108.3 28395.9 28569.6 28609.3 29250.6 28408.6 28446.0 28714.9 29019.9 29167.7 33174.0 33320.0 33427.0 34713.0 40733.6 39003.6 39012.6 39169.8 40277.0 40598.2 27238.0 29727.0 31828.7 31861.2 31991.5 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:43148.65 Median:22950.54 stddev:127448.76 (555.3%) stddev-sans-first:127765.84 11:24:11 INFO - PROCESS | 1637 | Values: 386.9 410.3 410.3 416.4 427.7 15867.6 17558.2 20637.6 20770.7 20864.9 21612.0 21827.9 22182.8 22804.7 23096.4 6410.3 6631.4 6679.3 6685.5 6686.1 6549.3 6659.4 7470.8 7784.3 7898.2 7730.5 7797.8 7926.9 7961.9 7967.9 7771.7 7785.8 7929.9 8022.2 8048.3 7605.2 7766.9 7781.3 7813.9 7832.1 28347.9 29113.6 29949.1 30495.7 30698.4 3299.5 3323.5 3327.4 3352.3 3385.2 17197.8 17363.7 17542.4 17905.7 17964.8 1906.9 2002.8 2009.5 2036.4 2067.6 106657.9 115152.0 132481.0 142259.3 156013.5 24892.0 25069.2 25253.6 25634.1 25669.1 28405.7 29581.9 43359.6 43667.3 43834.9 10029.3 10117.7 10223.3 10226.4 10296.9 8509.5 8539.2 8601.5 8663.8 8703.1 9227.7 9318.0 9335.7 9344.8 9405.9 3562.6 3615.9 3890.9 4198.0 4263.6 836.6 998.2 1020.0 1024.9 1032.7 20219.9 20295.9 20425.4 20866.8 20925.0 924.5 928.9 993.3 1026.8 1030.4 10234.1 10546.3 10550.4 10553.5 10626.3 748176.0 752656.0 761098.0 766149.2 809300.0 137649.0 156888.2 161716.0 162081.0 168525.2 1209.9 1215.7 1232.8 1235.5 1245.6 15566.5 16592.7 17514.3 17737.2 18315.0 47385.1 62064.4 62661.7 63513.0 64931.1 68127.0 83317.0 84935.2 85139.0 86002.1 9840.0 11355.8 11496.3 11857.8 11905.2 10333.0 12633.3 12786.5 12807.2 12879.7 9300.5 10480.3 11157.3 11337.7 11409.9 10071.3 10372.0 10405.0 10603.3 10747.8 10000.8 10810.6 11108.3 11272.9 11539.0 10820.2 10906.5 11365.4 11422.8 11768.3 9292.6 11219.8 11260.4 11516.0 11669.0 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6640.80 Median:2392.49 stddev:4173.23 (174.4%) stddev-sans-first:4166.17 11:24:11 INFO - PROCESS | 1637 | Values: 1374.2 1509.0 1535.6 1567.6 1571.3 10443.4 10894.3 10985.6 11014.5 11069.3 10962.2 11130.9 11157.1 11168.2 11218.5 2371.8 2422.8 2427.7 2429.2 2430.6 2316.1 2376.8 2385.1 2387.2 2391.9 1949.5 2303.8 2355.0 2407.2 2412.0 2332.1 2377.2 2393.1 2395.7 2407.9 1962.2 2372.8 2380.7 2381.3 2383.6 10903.4 10966.4 10972.2 11011.8 11012.8 5528.8 5875.4 5883.3 5888.2 5902.7 3489.4 3557.1 3586.9 3644.5 3654.4 1074.7 1168.6 1181.5 1211.1 1225.4 15151.7 15178.1 15179.3 15320.4 15322.2 4451.2 4702.9 4773.9 4790.4 4803.0 11420.5 11513.4 11530.7 11538.7 11550.4 5708.5 5760.0 5762.4 5770.2 5811.6 3938.3 5071.9 5220.6 5267.4 5324.4 3224.5 3301.5 3810.0 3828.9 3899.9 1056.7 1061.3 1068.2 1073.7 1073.8 832.5 938.9 961.7 964.5 1006.4 6459.4 6492.9 6494.0 6534.5 6711.5 1027.4 1052.7 1053.2 1067.9 1068.3 4087.6 4205.1 4215.5 4276.2 4278.7 12970.9 13593.0 13597.7 13601.5 13606.0 13242.7 13359.9 13445.9 13539.2 13668.9 10018.8 10079.1 10251.8 10252.9 10276.5 6473.2 6507.5 6523.7 6621.0 6635.3 11810.6 12032.0 12184.4 12202.7 12320.6 12353.4 12407.9 12454.6 12641.2 12642.1 7037.6 7162.6 7228.8 8521.6 8546.3 9989.4 10071.0 10197.2 10203.2 10219.3 8595.7 8622.4 8701.7 8740.9 8884.4 7233.4 8368.7 8372.8 8398.9 8543.4 7539.4 7768.0 7775.9 7968.9 7974.4 5832.9 5900.8 5939.9 6059.1 6075.1 5804.9 5826.7 5869.9 5891.6 6079.2 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4707.97 Median:5093.68 stddev:1274.98 (25.0%) stddev-sans-first:1234.96 11:24:11 INFO - PROCESS | 1637 | Values: 303.9 329.0 365.8 384.3 384.6 4199.6 4207.6 5017.6 5268.5 5472.6 4643.6 5124.1 5311.9 5320.9 5356.9 3780.2 3892.4 4418.4 4445.3 4454.9 4257.6 4368.4 4430.3 4472.9 4498.7 3995.6 4303.2 4332.7 4355.4 4390.6 3922.9 4405.3 4408.3 4414.8 4416.4 3520.7 3536.7 3666.4 4114.6 4143.1 4532.9 4725.2 4956.8 4973.1 5191.8 4225.7 5065.4 5088.7 5094.4 5106.9 4609.3 5264.4 5303.6 5411.5 5687.4 4383.1 4595.8 4764.6 4811.0 4871.8 5300.4 5720.8 5837.6 6035.2 6153.7 4589.3 4673.5 4680.0 4978.8 4989.6 4670.3 4723.3 5082.1 5086.5 5119.5 4761.7 4770.5 5079.5 5209.6 5224.8 4580.9 4902.6 5539.3 5593.2 5603.7 1483.7 1744.4 1771.7 1780.9 1782.4 1273.2 1342.3 1448.3 1455.6 1473.6 4331.2 4609.7 5093.0 5241.3 5359.2 5256.8 5540.1 5621.7 5799.8 5894.8 4452.0 4542.0 4725.4 5126.6 5376.4 2647.1 2734.0 2954.8 3023.3 3049.6 4709.5 5105.9 5987.9 6019.8 6034.3 5162.8 5514.3 5672.7 5695.6 5893.7 5323.8 5441.6 5654.3 5964.5 6111.2 5176.4 5249.6 5261.4 5278.0 5772.2 5244.6 5269.8 5494.4 5678.4 5730.0 5416.8 5652.6 5764.9 6028.9 6147.7 4599.2 5000.0 5072.0 5104.6 5104.6 5312.6 5490.0 5864.3 5876.9 5938.5 4934.5 5663.0 5673.2 5680.6 5755.5 4844.7 5639.7 5674.8 5690.2 5694.8 4467.4 4628.9 5466.9 5566.8 5568.7 4976.2 5179.6 5564.8 5701.1 5755.3 5304.5 5398.9 5470.8 5561.0 5610.1 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19833.21 Median:24904.58 stddev:28352.46 (113.8%) stddev-sans-first:28395.58 11:24:11 INFO - PROCESS | 1637 | Values: 697.7 715.9 766.3 774.8 846.6 15118.5 15410.8 15988.1 16112.8 16160.8 13987.9 14457.1 14596.5 14933.2 14973.2 928.8 954.8 958.6 958.9 962.8 901.4 953.2 959.5 961.6 961.8 971.6 980.3 981.8 983.3 984.1 975.4 977.1 977.5 978.5 978.8 717.6 721.3 723.6 725.2 725.3 15538.7 16054.4 16062.0 16555.0 16586.8 12173.8 12287.0 12497.0 12554.6 12762.0 12349.6 12677.6 12746.4 12792.7 12881.3 4379.2 4395.9 4450.6 4481.7 4660.7 107327.0 108159.0 110036.3 110686.0 112437.9 10475.8 10722.8 10750.9 10764.8 11326.0 16054.0 20814.7 21038.8 21240.0 21333.0 6537.3 6582.4 6623.3 6667.5 6739.2 3753.4 4150.4 4186.1 4200.5 4254.0 2818.3 3337.7 3378.0 3408.8 3418.4 618.3 619.4 626.6 626.6 627.8 487.6 499.4 503.7 515.3 530.2 9386.6 9512.0 9561.9 9567.9 9762.2 564.5 568.3 573.1 583.0 617.6 4616.3 4694.9 4991.5 5028.1 5083.3 60617.0 95368.0 97493.0 97569.5 98152.0 81709.2 82129.0 82956.3 86900.1 88920.0 58191.0 62996.4 77765.6 79290.0 80092.6 9666.3 9786.0 9786.3 9797.4 9835.3 46106.3 48699.0 49321.5 49380.5 49615.3 66077.7 69202.4 69651.3 72044.0 72209.6 11522.5 12095.8 12298.4 12333.7 12668.9 11108.5 14176.5 14185.4 14763.6 15615.8 13101.5 13789.6 13814.3 13840.7 14094.5 13390.7 13914.4 13995.0 14075.2 14579.4 12880.8 13701.2 13713.2 13864.5 14109.6 9687.4 9706.4 9851.5 10001.3 10029.4 9183.9 10231.8 10278.3 10287.9 10439.6 11:24:11 INFO - PROCESS | 1637 | -------- Summary: end -------- 11:24:11 INFO - PROCESS | 1637 | 11:24:11 INFO - TEST-INFO | 1637: exit 0 11:24:11 INFO - TEST-OK | dromaeo_css | took 1116569ms 11:24:11 INFO - TEST-START | kraken 11:24:11 INFO - operating with platform_type : mac_ 11:24:11 INFO - Initialising browser for kraken test... 11:24:11 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpLZwIUy/profile http://localhost:49243/getInfo.html) 11:24:14 INFO - PROCESS | 1649 | 2017-04-04 11:24:14.582 plugin-container[1650:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 11:24:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:14 INFO - PROCESS | 1649 | 2017-04-04 11:24:14.585 plugin-container[1650:10108] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 11:24:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:14 INFO - PROCESS | 1649 | 2017-04-04 11:24:14.585 plugin-container[1650:10108] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:24:14 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:24:14 INFO - PROCESS | 1649 | colorDepth:24 11:24:14 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:24:14 INFO - PROCESS | 1649 | __metrics 11:24:14 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 11:24:15 INFO - TEST-INFO | 1649: exit 0 11:24:15 INFO - Browser initialized. 11:24:15 INFO - Running cycle 1/1 for kraken test... 11:24:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpLZwIUy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:17 INFO - PROCESS | 1651 | 2017-04-04 11:24:17.728 plugin-container[1652:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 11:24:17 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:17 INFO - PROCESS | 1651 | 2017-04-04 11:24:17.728 plugin-container[1652:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 11:24:17 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:17 INFO - PROCESS | 1651 | 2017-04-04 11:24:17.728 plugin-container[1652:10229] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:24:27 INFO - PROCESS | 1651 | RSS: Main: 221061120 11:24:27 INFO - PROCESS | 1651 | 11:24:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 11:24:31 INFO - PROCESS | 1651 | RSS: Main: 235778048 11:24:31 INFO - PROCESS | 1651 | 11:24:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 11:24:34 INFO - PROCESS | 1651 | RSS: Main: 243585024 11:24:34 INFO - PROCESS | 1651 | 11:24:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 11:24:38 INFO - PROCESS | 1651 | RSS: Main: 247250944 11:24:38 INFO - PROCESS | 1651 | 11:24:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 11:24:41 INFO - PROCESS | 1651 | RSS: Main: 247578624 11:24:41 INFO - PROCESS | 1651 | 11:24:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:24:44 INFO - PROCESS | 1651 | RSS: Main: 248954880 11:24:44 INFO - PROCESS | 1651 | 11:24:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 11:24:47 INFO - PROCESS | 1651 | RSS: Main: 252964864 11:24:47 INFO - PROCESS | 1651 | 11:24:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 11:24:51 INFO - PROCESS | 1651 | RSS: Main: 252506112 11:24:51 INFO - PROCESS | 1651 | 11:24:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 11:24:55 INFO - PROCESS | 1651 | RSS: Main: 252821504 11:24:55 INFO - PROCESS | 1651 | 11:24:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:24:57 INFO - PROCESS | 1651 | RSS: Main: 251723776 11:24:57 INFO - PROCESS | 1651 | 11:24:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:25:00 INFO - PROCESS | 1651 | RSS: Main: 251650048 11:25:00 INFO - PROCESS | 1651 | 11:25:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:25:03 INFO - PROCESS | 1651 | RSS: Main: 251686912 11:25:03 INFO - PROCESS | 1651 | 11:25:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:25:06 INFO - PROCESS | 1651 | RSS: Main: 252076032 11:25:06 INFO - PROCESS | 1651 | 11:25:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:25:10 INFO - PROCESS | 1651 | RSS: Main: 251920384 11:25:10 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 11:25:12 INFO - PROCESS | 1651 | RSS: Main: 253784064 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | __start_tp_report 11:25:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:25:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:25:12 INFO - PROCESS | 1651 | |i|pagename|runs| 11:25:12 INFO - PROCESS | 1651 | |0;ai-astar;111;119;119;101;167;178;155;119;143;180 11:25:12 INFO - PROCESS | 1651 | |1;audio-beat-detection;145;111;112;164;98;133;150;146;113;103 11:25:12 INFO - PROCESS | 1651 | |2;audio-dft;197;198;248;270;195;205;195;196;197;198 11:25:12 INFO - PROCESS | 1651 | |3;audio-fft;83;80;63;89;64;63;75;85;67;72 11:25:12 INFO - PROCESS | 1651 | |4;audio-oscillator;85;76;73;57;59;70;116;61;76;109 11:25:12 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;88;89;91;88;89;88;89;81;137 11:25:12 INFO - PROCESS | 1651 | |6;imaging-darkroom;103;153;114;154;153;97;96;99;152;128 11:25:12 INFO - PROCESS | 1651 | |7;imaging-desaturate;89;99;122;91;93;92;93;90;91;112 11:25:12 INFO - PROCESS | 1651 | |8;json-parse-financial;68;67;67;67;70;70;68;70;70;71 11:25:12 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;57;64;66;67;74;76;56;59;59;60 11:25:12 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;85;82;82;72;92;72;104;107;104;87 11:25:12 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;140;138;138;139;144;149;153;155;205;164 11:25:12 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;170;163;225;190;168;171;173;165;191;171 11:25:12 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;82;87;66;64;66;65;68;64;66 11:25:12 INFO - PROCESS | 1651 | __end_tp_report 11:25:12 INFO - PROCESS | 1651 | __start_cc_report 11:25:12 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1014 11:25:12 INFO - PROCESS | 1651 | __end_cc_report 11:25:12 INFO - PROCESS | 1651 | __startTimestamp1491330312376__endTimestamp 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:25:12 INFO - PROCESS | 1651 | Number of tests: 14 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:139.20 Median:149.00 stddev:29.22 (19.6%) stddev-sans-first:29.16 11:25:12 INFO - PROCESS | 1651 | Values: 111.0 119.0 119.0 101.0 167.0 178.0 155.0 119.0 143.0 180.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:145.50 stddev:22.88 (15.7%) stddev-sans-first:23.37 11:25:12 INFO - PROCESS | 1651 | Values: 145.0 111.0 112.0 164.0 98.0 133.0 150.0 146.0 113.0 103.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:209.90 Median:198.00 stddev:26.54 (13.4%) stddev-sans-first:27.74 11:25:12 INFO - PROCESS | 1651 | Values: 197.0 198.0 248.0 270.0 195.0 205.0 195.0 196.0 197.0 198.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:74.10 Median:77.50 stddev:9.77 (12.6%) stddev-sans-first:9.82 11:25:12 INFO - PROCESS | 1651 | Values: 83.0 80.0 63.0 89.0 64.0 63.0 75.0 85.0 67.0 72.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:71.50 stddev:20.12 (28.1%) stddev-sans-first:21.18 11:25:12 INFO - PROCESS | 1651 | Values: 85.0 76.0 73.0 57.0 59.0 70.0 116.0 61.0 76.0 109.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:88.50 stddev:15.75 (17.8%) stddev-sans-first:16.61 11:25:12 INFO - PROCESS | 1651 | Values: 88.0 88.0 89.0 91.0 88.0 89.0 88.0 89.0 81.0 137.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:124.90 Median:153.50 stddev:25.93 (16.9%) stddev-sans-first:26.27 11:25:12 INFO - PROCESS | 1651 | Values: 103.0 153.0 114.0 154.0 153.0 97.0 96.0 99.0 152.0 128.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:91.50 stddev:11.03 (12.1%) stddev-sans-first:11.30 11:25:12 INFO - PROCESS | 1651 | Values: 89.0 99.0 122.0 91.0 93.0 92.0 93.0 90.0 91.0 112.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:70.00 stddev:1.55 (2.2%) stddev-sans-first:1.62 11:25:12 INFO - PROCESS | 1651 | Values: 68.0 67.0 67.0 67.0 70.0 70.0 68.0 70.0 70.0 71.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:65.00 stddev:6.96 (10.7%) stddev-sans-first:6.93 11:25:12 INFO - PROCESS | 1651 | Values: 57.0 64.0 66.0 67.0 74.0 76.0 56.0 59.0 59.0 60.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:82.00 stddev:12.82 (15.6%) stddev-sans-first:13.52 11:25:12 INFO - PROCESS | 1651 | Values: 85.0 82.0 82.0 72.0 92.0 72.0 104.0 107.0 104.0 87.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:151.00 stddev:20.38 (13.5%) stddev-sans-first:21.11 11:25:12 INFO - PROCESS | 1651 | Values: 140.0 138.0 138.0 139.0 144.0 149.0 153.0 155.0 205.0 164.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:172.00 stddev:18.85 (11.0%) stddev-sans-first:19.73 11:25:12 INFO - PROCESS | 1651 | Values: 170.0 163.0 225.0 190.0 168.0 171.0 173.0 165.0 191.0 171.0 11:25:12 INFO - PROCESS | 1651 | 11:25:12 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:66.50 stddev:8.09 (12.2%) stddev-sans-first:8.53 11:25:12 INFO - PROCESS | 1651 | Values: 67.0 82.0 87.0 66.0 64.0 66.0 65.0 68.0 64.0 66.0 11:25:12 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:25:12 INFO - PROCESS | 1651 | 11:25:13 INFO - TEST-INFO | 1651: exit 0 11:25:13 INFO - TEST-OK | kraken | took 61772ms 11:25:13 INFO - SUITE-END | took 1178s 11:25:13 INFO - Completed test suite (00:19:38) 11:25:13 INFO - javascript benchmark 11:25:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7708.120010967698, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.2131209821737, 3435.5533780608534, 3444.414449543577, 3458.1354661334667, 3483.0, 5885.676287804169, 5896.705164741762, 5932.970335148329, 5967.224260846084, 5977.5815692901515, 5903.4686878180955, 5989.281286245657, 6005.279366476029, 6013.278406591205, 6021.2473440819895, 4613.123506533765, 5044.798208071673, 5347.491988261109, 5376.924615077, 5469.206964990074, 4472.306792447176, 5306.575473962093, 5490.3411590609185, 5493.505584497394, 5536.861578460531, 5261.473852614746, 5273.709945952971, 5442.510174084332, 5459.836204913859, 5460.208269800876, 5312.203169524563, 5332.760025798849, 5333.946660533403, 5348.545373643245, 5353.411124776271, 5286.550643195333, 5319.0, 5319.9202011969855, 5360.705161216131, 5431.755570999315, 5322.467753224685, 5575.717584955442, 5985.610935289193, 6021.608595441282, 6028.1861948636815, 6096.146539484475, 6163.0, 6281.905771413432, 6302.0, 6308.990561510136, 6241.157443745127, 6241.906371404432, 6247.656378899159, 6250.0312451569625, 6284.811455656292, 6103.125543059995, 6107.358727333566, 6116.640127388561, 6131.724072416752, 6138.204480310026, 6481.578697384655, 6508.056331831858, 6545.21457425112, 6558.278589355263, 6576.375244351779, 5451.83644490658, 5634.492895639412, 6021.6989150542295, 6120.416658367975, 6194.101855230966, 6253.405926436982, 6283.088952102011, 6299.716512756937, 6342.112104305401, 6371.904421433682, 6246.063090536456, 6291.213598300213, 6295.055741638791, 6304.8423789404715, 6312.368763123651, 5388.434214407509, 6274.686265686789, 6293.590916590407, 6303.558750887394, 6307.24313082433, 4948.0, 4991.925121123186, 5015.072211640858, 5021.322121513549, 5063.291139240509, 4553.863384098482, 4565.703229290086, 4575.359449677047, 4583.518730533313, 4590.609798167126, 6012.428819262163, 6065.029595264737, 6098.32918378978, 6104.877902441926, 6116.877662446726, 5231.790728370823, 6172.043333283348, 6352.078948552434, 6368.0, 6373.235211774524, 5886.999210134288, 6011.609245399035, 6026.789062382795, 6033.7586496540525, 6073.696315184223, 5416.756245968941, 5508.118701007899, 5570.08093664549, 5641.210230567724, 5646.604737668324, 6563.507736919845, 6591.769288074895, 6629.834254143685, 6653.0, 6658.101156343929, 6144.3717414884195, 6365.681715914278, 6369.713362898773, 6381.904271435839, 6386.137871387489, 6321.273053598895, 6429.774957876639, 6453.774117905851, 6565.343465653587, 6595.637239951849, 6310.52671049664, 6313.116163736989, 6316.368363163647, 6318.273398559225, 6332.335104814112, 5006.249062640633, 5851.5663662402985, 6160.229971253593, 6332.335104813928, 6369.840753981188, 5070.239464080418, 6037.577369584087, 6111.144439778345, 6148.200733904564, 6184.690765461619, 5045.7981680732355, 6217.658028808278, 6302.054691796267, 6315.96842015787, 6319.431251187415, 5957.642541447527, 6186.381361863778, 6187.628742275478, 6209.223847019122, 6249.34381889895, 5236.39781425141, 6138.449706860676, 6195.287541932736, 6282.0, 6282.43458088756, 6040.969795150996, 6117.096718007557, 6120.08198770188, 6131.570790044654, 6142.04798256271], "value": 5797.398905302406, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.1580182789791, 745.4930647159932, 831.4138532334703, 959.5969692729053, 960.2510042167112, 29959.400811983774, 38835.22329553411, 40869.79565102174, 40891.38662920051, 41029.58970410295, 39448.21103577927, 39685.603143968554, 39711.20577588447, 39871.0, 39965.20069598607, 7890.921090789076, 8048.114707382183, 8518.829623407528, 10005.449700266483, 10686.78626427471, 10698.304610200352, 10735.78528429431, 10743.946280268588, 10795.568177272939, 10807.67576972692, 10744.301620394688, 10802.72993175173, 10828.350298982046, 10839.783204335909, 10872.728181795439, 8315.376346774015, 9158.49628270445, 9394.013628569039, 10801.945990270038, 10828.133749300037, 10452.529636166315, 10494.370337779681, 10526.631567895163, 10545.47272636365, 10565.260431769779, 33933.660663393544, 34156.48765268523, 34251.0, 34398.656013439795, 34439.48340774891, 3678.503402040717, 3722.664960153599, 3734.7572407793677, 3739.551253849528, 3784.9053773655605, 23748.525029499313, 23885.92513336904, 24367.26898193057, 25042.873070711863, 25054.997800087975, 3079.676633953425, 3099.364630250793, 3175.2696879717564, 3260.543523906655, 3276.6068071831533, 314309.0, 314427.0, 338877.0, 343414.0, 447001.7649911762, 51247.7437612817, 51647.48352516502, 54101.0, 54163.72918135384, 54348.18477722837, 47032.294515582296, 56699.71650141723, 56826.7158664204, 57065.71467142638, 57118.42881571214, 14956.027858189183, 14959.626009349635, 15106.169160696047, 15133.394664213529, 15195.0, 12861.678458038437, 12878.905293050195, 12888.0, 12919.612411627666, 12935.223886566648, 13601.251931143684, 13627.114237574526, 13648.317584120952, 13714.51999180024, 13751.243681597409, 4978.0292842896015, 6279.7802076928, 6297.118403423525, 6300.716467758962, 6310.337414571496, 865.1996902864825, 933.0436302789559, 941.1153515695224, 945.4847108325979, 996.2627655534852, 27112.864435677697, 27600.033998809944, 28291.575626365622, 28394.0, 28587.71412285892, 1050.4275170032197, 1051.5898799468214, 1054.4569546683465, 1086.7989421956963, 1092.7978324010085, 14181.0, 14361.856381436051, 14597.270136493133, 14801.333939972727, 15134.621634459227, 500173.0, 501219.0, 501968.0, 502974.0, 504595.0, 56394.87210255772, 56734.71632641811, 57030.429695702514, 57150.7142464285, 57479.0, 1437.3244575049919, 1447.182341976801, 1480.4448331875547, 1484.0353770049553, 1488.2930607961132, 26610.201693949213, 27631.30921726973, 27817.860910696127, 28107.016254431815, 28107.875684972372, 48653.75673121612, 48785.75607121942, 48984.51015489942, 49010.75494622504, 49169.26246106383, 450337.0, 456697.7165114153, 458124.0, 458450.0, 459213.0, 30356.392872142875, 31052.22369440782, 32236.516452253705, 32258.516122258654, 35727.64272357243, 31996.0, 34264.0, 38928.80535597304, 40533.98665033398, 40759.59240407558, 28108.297292567848, 28395.858020709766, 28569.571456427755, 28609.28476788097, 29250.561241580966, 28408.57387139236, 28446.0, 28714.85642571774, 29019.85490072536, 29167.7083229165, 33174.0, 33320.0, 33427.0, 34713.0, 40733.59266407298, 39003.609963899995, 39012.60987390203, 39169.804150979064, 40277.0, 40598.1880362397, 27238.0, 29727.0, 31828.726850925705, 31861.20346991344, 31991.520127197644], "value": 18960.88775423462, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.8684647219945, 410.2635768795013, 410.3414020497102, 416.444047196992, 427.7390791617113, 15867.603309917258, 17558.209880555372, 20637.587248255026, 20770.6884396734, 20864.89567552162, 21612.027458764354, 21827.890860545715, 22182.779947102943, 22804.657930131063, 23096.42258943527, 6410.326915673857, 6631.403173714365, 6679.332066793306, 6685.465162786965, 6686.063951046857, 6549.312322206171, 6659.4006539411685, 7470.813229669247, 7784.338331241823, 7898.210178982114, 7730.4975176613625, 7797.844043119134, 7926.8810967835525, 7961.880571791427, 7967.880481792778, 7771.72798952034, 7785.8053548661455, 7929.881051784228, 8022.157673444321, 8048.31589314906, 7605.163432022473, 7766.922330776676, 7781.3385862201785, 7813.882791758128, 7832.0601527817025, 28347.86608535656, 29113.56329655057, 29949.101526954226, 30495.69504304973, 30698.386032279454, 3299.472084466474, 3323.5180898769545, 3327.434336162858, 3352.3127758809387, 3385.18755498681, 17197.828021719746, 17363.739543906853, 17542.386016489363, 17905.7314140288, 17964.820351796578, 1906.9321180139166, 2002.8297594704322, 2009.4775358406773, 2036.3505560495898, 2067.6071546406133, 106657.93342066635, 115152.0, 132481.0, 142259.28870355582, 156013.50611061894, 24892.012958445102, 25069.247922562354, 25253.62119568208, 25634.102806401686, 25669.10158144456, 28405.71594284072, 29581.85209073941, 43359.56640433618, 43667.34498982518, 43834.90412739707, 10029.29794914349, 10117.747056323504, 10223.28437009417, 10226.41490568963, 10296.897031029745, 8509.489430634181, 8539.231469167804, 8601.483910965362, 8663.783405414915, 8703.086175951561, 9227.677031303872, 9318.0, 9335.673251436303, 9344.766380840398, 9405.858912116324, 3562.608113107556, 3615.9096022599647, 3890.9299942515468, 4197.979010104991, 4263.637590804754, 836.6318819719304, 998.1715176403568, 1019.9694009179736, 1024.933379330356, 1032.7314898126542, 20219.898900505403, 20295.898520507304, 20425.38723838287, 20866.791332086486, 20925.0, 924.5377311344328, 928.8699582058517, 993.339429279521, 1026.7843752811843, 1030.386919782743, 10234.078932896075, 10546.314489558154, 10550.366977981346, 10553.525091370906, 10626.256162068736, 748176.0, 752656.0, 761098.0, 766149.1692541501, 809300.0, 137649.0, 156888.21555892148, 161716.0, 162081.0, 168525.1573742148, 1209.8608660004036, 1215.6839221802395, 1232.7596118756935, 1235.4502246500401, 1245.6263121063644, 15566.455174068853, 16592.668146637243, 17514.299428023245, 17737.201825918124, 18315.0, 47385.05229895452, 62064.379356205856, 62661.686691566254, 63513.0, 64931.104133752124, 68127.0, 83317.0, 84935.15064849272, 85139.0, 86002.13997860192, 9839.966803485531, 11355.772884542097, 11496.310221386744, 11857.762844742885, 11905.226160299553, 10333.0, 12633.305168215837, 12786.48854045865, 12807.167534110253, 12879.742405152032, 9300.534973251446, 10480.266381353233, 11157.27477713946, 11337.716557086138, 11409.885901141215, 10071.295009349275, 10372.01465860742, 10405.011523905217, 10603.257771956198, 10747.838782418426, 10000.849902261332, 10810.567577297135, 11108.277961932448, 11272.943635281772, 11539.0, 10820.242583019428, 10906.509207085859, 11365.431728413712, 11422.771544569228, 11768.293902365886, 9292.58183381763, 11219.775604488028, 11260.38067906273, 11515.96356327901, 11669.0], "value": 11728.951769901521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.1617613255914, 1508.9924550377245, 1535.616095976006, 1567.6316065724552, 1571.269359747717, 10443.425611591369, 10894.346339219652, 10985.615503457371, 11014.50434730438, 11069.280496767704, 10962.177836662262, 11130.888691113087, 11157.051650609708, 11168.218224724273, 11218.495167717452, 2371.7628237176273, 2422.842515236504, 2427.7329493755674, 2429.1983645397063, 2430.647556104364, 2316.1198744477074, 2376.786089251967, 2385.057902128658, 2387.236084452976, 2391.9162829300976, 1949.4736421166276, 2303.7696230376996, 2354.9764502355015, 2407.1935901473025, 2412.0351859256352, 2332.055517515409, 2377.2036367816804, 2393.066703985447, 2395.6765836612008, 2407.903683852644, 1962.2249211561352, 2372.810175185998, 2380.666706661069, 2381.2618088392655, 2383.6305372667266, 10903.400312982783, 10966.396848173346, 10972.177086718528, 11011.779764404748, 11012.779744405067, 5528.806491772809, 5875.412458754133, 5883.294004719418, 5888.234529511179, 5902.704864756745, 3489.3719130556487, 3557.0751604582993, 3586.9282614347826, 3644.5444319460066, 3654.433562797744, 1074.6829685242794, 1168.5909931523984, 1181.4978634080567, 1211.1400905357207, 1225.40567824605, 15151.696966060726, 15178.089314641156, 15179.316930738143, 15320.387184512718, 15322.15728134952, 4451.198784218807, 4702.85891423251, 4773.880652983634, 4790.4251489820745, 4802.991371811959, 11420.543178272776, 11513.424328783527, 11530.711732206762, 11538.653840384633, 11550.364729939765, 5708.514776244066, 5759.971200144057, 5762.423757624204, 5770.2498675171955, 5811.622244554175, 3938.3107956107565, 5071.923921141186, 5220.634555581149, 5267.367915850046, 5324.361076670824, 3224.4840825467813, 3301.5047742838287, 3809.961900381016, 3828.885133446001, 3899.922001559953, 1056.693558867935, 1061.341967979842, 1068.1614932278123, 1073.6993641780314, 1073.7852429514128, 832.5088197963231, 938.863864739609, 961.7018724195382, 964.5129209749032, 1006.4414250091123, 6459.386358295955, 6492.902606460907, 6493.967530162319, 6534.477241820643, 6711.463082953352, 1027.429776474064, 1052.6894566103017, 1053.2048303530785, 1067.882532921378, 1068.3322923173016, 4087.6321131097748, 4205.137946720976, 4215.515215750167, 4276.2089013532595, 4278.657707383401, 12970.935145324212, 13592.980526460356, 13597.728045439233, 13601.523946661819, 13606.047576669538, 13242.686362143215, 13359.933200333937, 13445.932770336085, 13539.172211751484, 13668.93165534166, 10018.799624007625, 10079.092881640687, 10251.794964100676, 10252.948735256276, 10276.486175691185, 6473.158489396348, 6507.479401647952, 6523.739050437929, 6621.0, 6635.336466353326, 11810.645680629596, 12032.037437004843, 12184.390780461119, 12202.694932626755, 12320.630381088582, 12353.3823308836, 12407.93796031014, 12454.626361209179, 12641.178323408949, 12642.115051946275, 7037.612931288828, 7162.639098571255, 7228.783136505913, 8521.573921304034, 8546.273566746648, 9989.400635961574, 10071.0, 10197.23520735933, 10203.234757393077, 10219.284650074424, 8595.742127736177, 8622.353323500636, 8701.651933922825, 8740.912590874264, 8884.422512536665, 7233.3851622612665, 8368.748937531884, 8372.832543348977, 8398.916010839814, 8543.444676096035, 7539.396848252017, 7767.961160194162, 7775.883361749691, 7968.920310796818, 7974.362051035787, 5832.883342333214, 5900.822975310747, 5939.851503712442, 6059.091136329585, 6075.119107729266, 5804.854878628068, 5826.73779679907, 5869.941300586939, 5891.646501209942, 6079.240094988127], "value": 5072.555551100103, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9133846853647, 328.9703926646602, 365.8170914542729, 384.30057295721787, 384.567361718067, 4199.601037901402, 4207.642350400216, 5017.623678224134, 5268.525832675058, 5472.562195024393, 4643.628509719223, 5124.128898087325, 5311.9468805311935, 5320.9467905320935, 5356.91964620531, 3780.235141409359, 3892.3966785148345, 4418.403515525411, 4445.266531022386, 4454.8886277842985, 4257.595528424795, 4368.410264614284, 4430.3133014382665, 4472.888177795565, 4498.685092043558, 3995.641481896155, 4303.225419424502, 4332.718373305741, 4355.412019377391, 4390.560943905616, 3922.921541569167, 4405.339199120125, 4408.25059739845, 4414.84548040817, 4416.381706561084, 3520.6831385175456, 3536.6816986471085, 3666.358387282215, 4114.588541145891, 4143.067809742814, 4532.864014079583, 4725.243960966229, 4956.752162391866, 4973.104841128596, 5191.831837836495, 4225.704200705952, 5065.366829146356, 5088.720120393377, 5094.363204599425, 5106.939982713714, 4609.285560738057, 5264.394594621591, 5303.6398802532885, 5411.458854114557, 5687.407525892757, 4383.123375324948, 4595.770211489412, 4764.6426518011285, 4810.951890481086, 4871.782054486378, 5300.3639563252655, 5720.828375148711, 5837.562182836305, 6035.223797053361, 6153.661548614825, 4589.33454649074, 4673.509281525391, 4680.0, 4978.825741099116, 4989.625778066659, 4670.318685273299, 4723.267893476516, 5082.110630639623, 5086.516780905808, 5119.462456442095, 4761.714337128956, 4770.451398089194, 5079.466656001141, 5209.557187639093, 5224.764885580158, 4580.908381832344, 4902.583280421204, 5539.25220095282, 5593.244911936918, 5603.691796951207, 1483.6958423523367, 1744.3981826269917, 1771.654527367151, 1780.9376671816424, 1782.4474412932032, 1273.1857103627294, 1342.2751714074486, 1448.2975756757965, 1455.5706066710345, 1473.62422582243, 4331.242032644274, 4609.746463944515, 5093.0, 5241.256662720244, 5359.249705041298, 5256.763445644955, 5540.113581826889, 5621.690807005572, 5799.768009279582, 5894.793682221101, 4451.9554804451545, 4541.977290113528, 4725.39336625549, 5126.5898728101665, 5376.4085950545405, 2647.113217072302, 2734.0, 2954.778391620637, 3023.349979754363, 3049.5819443958667, 4709.458412282563, 5105.8723531911255, 5987.880242395215, 6019.819405417845, 6034.336223015464, 5162.767675454538, 5514.338279406497, 5672.716364181774, 5695.629784064023, 5893.734781934823, 5323.787048518094, 5441.564674826083, 5654.293213348332, 5964.463198312086, 6111.236095488064, 5176.41941256137, 5249.611426800873, 5261.447548007557, 5278.0, 5772.161512594078, 5244.580433565382, 5269.841904742864, 5494.395616482104, 5678.403767604507, 5730.023142015998, 5416.8104116355735, 5652.632578882524, 5764.942350576441, 6028.8794224116145, 6147.694614519555, 4599.170870585805, 4999.999999999942, 5071.974640126776, 5104.566111880532, 5104.642675012714, 5312.601554883322, 5490.0, 5864.32560255559, 5876.911846322223, 5938.485686150004, 4934.5312195341385, 5662.971685141548, 5673.205751194919, 5680.573956953328, 5755.510781583444, 4844.660873738804, 5639.661620302795, 5674.829755107354, 5690.179142674305, 5694.829155125353, 4467.441569803775, 4628.94966903959, 5466.890662186814, 5566.77732890696, 5568.749406276646, 4976.168350370267, 5179.6273987394125, 5564.8330550083565, 5701.144828275792, 5755.3381361144, 5304.469553044664, 5398.946010539844, 5470.753816078206, 5561.0258358283845, 5610.09256852672], "value": 4341.042412017848, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.6720941157661, 715.8532500837329, 766.3179770004697, 774.752079334613, 846.6359465429865, 15118.546443606696, 15410.845891541081, 15988.12065336406, 16112.758308625365, 16160.75758863618, 13987.930060349683, 14457.132572045684, 14596.489122880701, 14933.178675172861, 14973.176475293882, 928.8095940332232, 954.8233576788282, 958.6165533786472, 958.8753462049958, 962.8205448325815, 901.3690416708298, 953.1897886796203, 959.4627008875018, 961.6105477281712, 961.8076384723048, 971.5773638467253, 980.2843923935532, 981.7938232971085, 983.3018556824663, 984.0503913723255, 975.4293738163183, 977.0913050862695, 977.5258999385328, 978.5254151736438, 978.8482785168275, 717.587387252332, 721.3039416962604, 723.583939234942, 725.2203880828079, 725.2783480436947, 15538.689226215525, 16054.43809466663, 16062.036277823368, 16555.0, 16586.75119873203, 12173.817392739116, 12287.0, 12497.0, 12554.623361299176, 12762.0, 12349.567765128268, 12677.619671409873, 12746.362681865963, 12792.680182995406, 12881.291528965903, 4379.1679580879845, 4395.868123956255, 4450.599446049839, 4481.731096134243, 4660.673752837302, 107327.0, 108159.0, 110036.34945475824, 110686.0, 112437.87562124437, 10475.790484190426, 10722.839157412645, 10750.892491074988, 10764.784704306026, 11326.0, 16054.0, 20814.687779683318, 21038.78961210399, 21240.0, 21333.040013199443, 6537.313582073909, 6582.407583317525, 6623.304553021863, 6667.494721152651, 6739.191297044386, 3753.3806921857586, 4150.439690641725, 4186.1418409226035, 4200.453940987713, 4254.0, 2818.3236023354657, 3337.6996070353784, 3378.0, 3408.829558522064, 3418.4359580669407, 618.3105836991722, 619.3868070610064, 626.5645376463406, 626.6083697688945, 627.7802769030894, 487.6464563191658, 499.3558309780415, 503.6952643650612, 515.2528833191886, 530.2338121414596, 9386.577604007836, 9512.048795120432, 9561.856572151424, 9567.90432095684, 9762.219022478184, 564.4505831492579, 568.3123420661046, 573.1173992052193, 583.0263460021774, 617.561531312773, 4616.28447590624, 4694.906101877943, 4991.50084991498, 5028.145215313405, 5083.288339632454, 60617.0, 95368.0, 97493.0, 97569.5121524402, 98152.0, 81709.18290817016, 82129.0, 82956.3408731822, 86900.13099869047, 88920.0, 58191.0, 62996.370036299966, 77765.61117194491, 79290.01774955672, 80092.5995370031, 9666.295663234896, 9786.0, 9786.266030047633, 9797.363171393836, 9835.311528192959, 46106.31149508098, 48699.0, 49321.50678493169, 49380.53097345046, 49615.255771164186, 66077.67844643176, 69202.44296748714, 69651.30348696448, 72044.0, 72209.63895180491, 11522.53909843597, 12095.81856272139, 12298.385080746106, 12333.69165770827, 12668.936655316664, 11108.500117494894, 14176.50382236658, 14185.432582696576, 14763.630909226926, 15615.76576351333, 13101.475940962637, 13789.586312410644, 13814.309284535531, 13840.723185536031, 14094.50669226572, 13390.665233369244, 13914.443422262995, 13995.0, 14075.225862577656, 14579.416823327372, 12880.806787898002, 13701.246431446367, 13713.17720936747, 13864.514741983981, 14109.576712698636, 9687.370320929118, 9706.369086009387, 9851.45816980073, 10001.34991225568, 10029.398236105857, 9183.862242066509, 10231.84652230232, 10278.280520363503, 10287.897121028695, 10439.63461278882], "value": 7387.1630393602, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 119.0, 119.0, 101.0, 167.0, 178.0, 155.0, 119.0, 143.0, 180.0], "value": 139.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 112.0, 164.0, 98.0, 133.0, 150.0, 146.0, 113.0, 103.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 248.0, 270.0, 195.0, 205.0, 195.0, 196.0, 197.0, 198.0], "value": 209.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 63.0, 89.0, 64.0, 63.0, 75.0, 85.0, 67.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 76.0, 73.0, 57.0, 59.0, 70.0, 116.0, 61.0, 76.0, 109.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 91.0, 88.0, 89.0, 88.0, 89.0, 81.0, 137.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 153.0, 114.0, 154.0, 153.0, 97.0, 96.0, 99.0, 152.0, 128.0], "value": 124.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 99.0, 122.0, 91.0, 93.0, 92.0, 93.0, 90.0, 91.0, 112.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 70.0, 70.0, 68.0, 70.0, 70.0, 71.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 66.0, 67.0, 74.0, 76.0, 56.0, 59.0, 59.0, 60.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 82.0, 72.0, 92.0, 72.0, 104.0, 107.0, 104.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 138.0, 139.0, 144.0, 149.0, 153.0, 155.0, 205.0, 164.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 225.0, 190.0, 168.0, 171.0, 173.0, 165.0, 191.0, 171.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 82.0, 87.0, 66.0, 64.0, 66.0, 65.0, 68.0, 64.0, 66.0], "value": 69.5, "unit": "score"}]}]} 11:25:13 INFO - Return code: 0 11:25:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:25:13 ERROR - # TBPL SUCCESS # 11:25:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:25:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:25:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:25:13 INFO - 2017-04-04 11:25:13.727 screenresolution[1656:10614] starting screenresolution argv=screenresolution get 11:25:13 INFO - 2017-04-04 11:25:13.729 screenresolution[1656:10614] Display 0: 1600x1200x32@60 11:25:13 INFO - 2017-04-04 11:25:13.735 screenresolution[1657:10617] starting screenresolution argv=screenresolution list 11:25:13 INFO - Available Modes on Display 0 11:25:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:25:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:25:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:25:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:25:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:25:13 INFO - Intel Iris: 11:25:13 INFO - Chipset Model: Intel Iris 11:25:13 INFO - Type: GPU 11:25:13 INFO - Bus: Built-In 11:25:13 INFO - VRAM (Dynamic, Max): 1536 MB 11:25:13 INFO - Vendor: Intel (0x8086) 11:25:13 INFO - Device ID: 0x0a2e 11:25:13 INFO - Revision ID: 0x0009 11:25:13 INFO - Displays: 11:25:13 INFO - Display: 11:25:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:25:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:25:13 INFO - Display Serial Number: 1600x1200 60 11:25:13 INFO - Main Display: Yes 11:25:13 INFO - Mirror: Off 11:25:13 INFO - Online: Yes 11:25:13 INFO - Rotation: Supported 11:25:13 INFO - Return code: 0 11:25:13 INFO - Running post-action listener: _resource_record_post_action 11:25:13 INFO - [mozharness: 2017-04-04 18:25:13.978189Z] Finished run-tests step (success) 11:25:13 INFO - Running post-run listener: _resource_record_post_run 11:25:14 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 39553536; Write bytes: 512650240; Read time: 386; Write time: 1639 11:25:14 INFO - TinderboxPrint: CPU usage
54.8% 11:25:14 INFO - TinderboxPrint: I/O read bytes / time
39,553,536 / 386 11:25:14 INFO - TinderboxPrint: I/O write bytes / time
512,650,240 / 1,639 11:25:14 INFO - TinderboxPrint: CPU idle
2,167.9 (45.2%) 11:25:14 INFO - TinderboxPrint: CPU system
968.6 (20.2%) 11:25:14 INFO - TinderboxPrint: CPU user
1,661.2 (34.6%) 11:25:14 INFO - TinderboxPrint: Swap in / out
248,078,336 / 0 11:25:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203825664; Write bytes: 201340928; Read time: 15695; Write time: 483 11:25:14 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 37550592; Write bytes: 300516352; Read time: 359; Write time: 1124 11:25:14 INFO - Running post-run listener: _upload_blobber_files 11:25:14 INFO - Blob upload gear active. 11:25:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:25:14 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 11:25:14 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:25:14 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:25:14 INFO - (blobuploader) - INFO - Open directory for files ... 11:25:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:25:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:25:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:15 INFO - (blobuploader) - INFO - Done attempting. 11:25:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:25:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:25:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:16 INFO - (blobuploader) - INFO - Done attempting. 11:25:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:25:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:25:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:17 INFO - (blobuploader) - INFO - Done attempting. 11:25:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:25:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:19 INFO - (blobuploader) - INFO - Done attempting. 11:25:19 INFO - (blobuploader) - INFO - Iteration through files over. 11:25:19 INFO - Return code: 0 11:25:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:25:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:25:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bad62d4ef9ca5628a3670395950b2a3831e8944fb8d9f1c258ed6aceb12682c2fc72bd59247da44d78dc2e88474ce59bd59f7c0a0f8d417ac23fe2bea4b65e02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69bf7556f6cbdd56c3c3f834b84d7da29bca9c8bfd6270b9a9a88761b7df53e26ce803e59ee1cbdd70e6cbc0f31db98722e318b6d5e59b63297f584351efb1d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e2a6bd98114c149c1e8d2f6027328ed15b46a33ec3e3e8a78a9a8e44f109fabad0a01a1f3651f3a6e0688f555fc7b7ce18afd1f7977e47a2bd3ba743f5338c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81503f64bea96c3c60466480614b3cb03398cbb53f7e4fe8608697e67fcd955dffc86becc1fe9571055bcae414b5bfa2388999dd29bf04a9c495793edfd0b499"} 11:25:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:25:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:25:19 INFO - Contents: 11:25:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bad62d4ef9ca5628a3670395950b2a3831e8944fb8d9f1c258ed6aceb12682c2fc72bd59247da44d78dc2e88474ce59bd59f7c0a0f8d417ac23fe2bea4b65e02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69bf7556f6cbdd56c3c3f834b84d7da29bca9c8bfd6270b9a9a88761b7df53e26ce803e59ee1cbdd70e6cbc0f31db98722e318b6d5e59b63297f584351efb1d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e2a6bd98114c149c1e8d2f6027328ed15b46a33ec3e3e8a78a9a8e44f109fabad0a01a1f3651f3a6e0688f555fc7b7ce18afd1f7977e47a2bd3ba743f5338c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81503f64bea96c3c60466480614b3cb03398cbb53f7e4fe8608697e67fcd955dffc86becc1fe9571055bcae414b5bfa2388999dd29bf04a9c495793edfd0b499"} 11:25:19 INFO - Running post-run listener: copy_logs_to_upload_dir 11:25:19 INFO - Copying logs to upload dir... 11:25:19 INFO - mkdir: /builds/slave/test/build/upload/logs 11:25:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.964521 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-04 11:25:19.780319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 11:25:19.784253) ========= 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.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bad62d4ef9ca5628a3670395950b2a3831e8944fb8d9f1c258ed6aceb12682c2fc72bd59247da44d78dc2e88474ce59bd59f7c0a0f8d417ac23fe2bea4b65e02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69bf7556f6cbdd56c3c3f834b84d7da29bca9c8bfd6270b9a9a88761b7df53e26ce803e59ee1cbdd70e6cbc0f31db98722e318b6d5e59b63297f584351efb1d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e2a6bd98114c149c1e8d2f6027328ed15b46a33ec3e3e8a78a9a8e44f109fabad0a01a1f3651f3a6e0688f555fc7b7ce18afd1f7977e47a2bd3ba743f5338c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81503f64bea96c3c60466480614b3cb03398cbb53f7e4fe8608697e67fcd955dffc86becc1fe9571055bcae414b5bfa2388999dd29bf04a9c495793edfd0b499"} build_url:https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010348 build_url: 'https://queue.taskcluster.net/v1/task/Kc4vxMs_Tt2idLrbCyUgkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bad62d4ef9ca5628a3670395950b2a3831e8944fb8d9f1c258ed6aceb12682c2fc72bd59247da44d78dc2e88474ce59bd59f7c0a0f8d417ac23fe2bea4b65e02", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69bf7556f6cbdd56c3c3f834b84d7da29bca9c8bfd6270b9a9a88761b7df53e26ce803e59ee1cbdd70e6cbc0f31db98722e318b6d5e59b63297f584351efb1d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e2a6bd98114c149c1e8d2f6027328ed15b46a33ec3e3e8a78a9a8e44f109fabad0a01a1f3651f3a6e0688f555fc7b7ce18afd1f7977e47a2bd3ba743f5338c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81503f64bea96c3c60466480614b3cb03398cbb53f7e4fe8608697e67fcd955dffc86becc1fe9571055bcae414b5bfa2388999dd29bf04a9c495793edfd0b499"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 11:25:19.818551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:25:19.818896) ========= 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.iUkhs03RJg/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.st19nk8Vsp/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.005215 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 11:25:19.867667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 11:25:19.868028) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 11:25:19.868347) ========= ========= Total master_lag: 0.48 =========