builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0073 starttime: 1492075679.65 results: success (0) buildid: 20170413004024 builduid: be2c2067165d4c598db10efa2aab9c48 revision: 4c78da5f6ddacce5332ead124aa734635fc83d8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.649041) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.649474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.649750) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.675961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.676346) ========= 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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-13 02:27:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2017-04-13 02:27:59 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.828831) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.829143) ========= 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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.028171 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:59.880337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 02:27:59.880697) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4c78da5f6ddacce5332ead124aa734635fc83d8f --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 4c78da5f6ddacce5332ead124aa734635fc83d8f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-13 02:27:59,996 truncating revision to first 12 chars 2017-04-13 02:27:59,996 Setting DEBUG logging. 2017-04-13 02:27:59,996 attempt 1/10 2017-04-13 02:27:59,996 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4c78da5f6dda?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 02:28:01,896 unpacking tar archive at: mozilla-aurora-4c78da5f6dda/testing/mozharness/ program finished with exit code 0 elapsedTime=2.165569 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 02:28:02.383432) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:28:02.383760) ========= script_repo_revision: 4c78da5f6ddacce5332ead124aa734635fc83d8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:28:02.384142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:28:02.384422) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:28:02.404938) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-13 02:28:02.405360) ========= /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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:28:02 INFO - MultiFileLogger online at 20170413 02:28:02 in /builds/slave/test 02:28:02 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 02:28:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:02 INFO - {'append_to_log': False, 02:28:02 INFO - 'base_work_dir': '/builds/slave/test', 02:28:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:28:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:02 INFO - 'branch': 'Mozilla-Aurora', 02:28:02 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:02 INFO - 'config_files': ('talos/mac_config.py',), 02:28:02 INFO - 'default_actions': ('clobber', 02:28:02 INFO - 'read-buildbot-config', 02:28:02 INFO - 'download-and-extract', 02:28:02 INFO - 'populate-webroot', 02:28:02 INFO - 'create-virtualenv', 02:28:02 INFO - 'install', 02:28:02 INFO - 'run-tests'), 02:28:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:02 INFO - 'download_minidump_stackwalk': True, 02:28:02 INFO - 'download_symbols': 'ondemand', 02:28:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:28:02 INFO - 'tooltool.py': '/tools/tooltool.py', 02:28:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:28:02 INFO - '/tools/misc-python/virtualenv.py')}, 02:28:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:02 INFO - 'gecko_profile': False, 02:28:02 INFO - 'gecko_profile_interval': 0, 02:28:02 INFO - 'installer_path': 'installer.exe', 02:28:02 INFO - 'log_level': 'info', 02:28:02 INFO - 'log_name': 'talos', 02:28:02 INFO - 'log_to_console': True, 02:28:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:28:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:28:02 INFO - 'opt_config_files': (), 02:28:02 INFO - 'pip_index': False, 02:28:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:02 INFO - 'cmd': ('bash', 02:28:02 INFO - '-c', 02:28:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:02 INFO - 'enabled': True, 02:28:02 INFO - 'halt_on_failure': False, 02:28:02 INFO - 'name': 'check_screen_resolution'},), 02:28:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:28:02 INFO - 'cmd': ('bash', 02:28:02 INFO - '-c', 02:28:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:28:02 INFO - 'enabled': True, 02:28:02 INFO - 'halt_on_failure': False, 02:28:02 INFO - 'name': 'check_screen_resolution'},), 02:28:02 INFO - 'run_cmd_checks_enabled': True, 02:28:02 INFO - 'suite': 'dromaeojs-e10s', 02:28:02 INFO - 'system_bits': '32', 02:28:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:02 INFO - 'title': 't-yosemite-r7-0073', 02:28:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:02 INFO - 'use_talos_json': True, 02:28:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:02 INFO - 'work_dir': 'build'} 02:28:02 INFO - [mozharness: 2017-04-13 09:28:02.635345Z] Running clobber step. 02:28:02 INFO - Running pre-action listener: _resource_record_pre_action 02:28:02 INFO - Running main action method: clobber 02:28:02 INFO - rmtree: /builds/slave/test/build 02:28:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:07 INFO - Running post-action listener: _resource_record_post_action 02:28:07 INFO - [mozharness: 2017-04-13 09:28:07.259342Z] Finished clobber step (success) 02:28:07 INFO - [mozharness: 2017-04-13 09:28:07.259596Z] Running read-buildbot-config step. 02:28:07 INFO - Running pre-action listener: _resource_record_pre_action 02:28:07 INFO - Running main action method: read_buildbot_config 02:28:07 INFO - Using buildbot properties: 02:28:07 INFO - { 02:28:07 INFO - "project": "", 02:28:07 INFO - "product": "firefox", 02:28:07 INFO - "script_repo_revision": "production", 02:28:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:28:07 INFO - "repository": "", 02:28:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:28:07 INFO - "buildid": "20170413004024", 02:28:07 INFO - "stage_platform": "macosx64", 02:28:07 INFO - "basedir": "/builds/slave/test", 02:28:07 INFO - "nightly_build": "True", 02:28:07 INFO - "buildnumber": 14, 02:28:07 INFO - "slavename": "t-yosemite-r7-0073", 02:28:07 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:28:07 INFO - "platform": "yosemite_r7", 02:28:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:28:07 INFO - "branch": "mozilla-aurora", 02:28:07 INFO - "repo_path": "releases/mozilla-aurora", 02:28:07 INFO - "revision": "4c78da5f6ddacce5332ead124aa734635fc83d8f", 02:28:07 INFO - "pgo_build": "False", 02:28:07 INFO - "builduid": "be2c2067165d4c598db10efa2aab9c48", 02:28:07 INFO - "slavebuilddir": "test" 02:28:07 INFO - } 02:28:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:28:07 INFO - Running post-action listener: _resource_record_post_action 02:28:07 INFO - [mozharness: 2017-04-13 09:28:07.265698Z] Finished read-buildbot-config step (success) 02:28:07 INFO - [mozharness: 2017-04-13 09:28:07.265888Z] Running download-and-extract step. 02:28:07 INFO - Running pre-action listener: _resource_record_pre_action 02:28:07 INFO - Running main action method: download_and_extract 02:28:07 INFO - mkdir: /builds/slave/test/build/tests 02:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:07 INFO - trying https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:28:07 INFO - Downloading https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/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 02:28:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/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 02:28:08 INFO - Downloaded 1199 bytes. 02:28:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:28:08 INFO - Using the following test package requirements: 02:28:08 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:28:08 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:28:08 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:28:08 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'jsshell-mac.zip'], 02:28:08 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:28:08 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:28:08 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:28:08 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:28:08 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:28:08 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:28:08 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:28:08 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:28:10 INFO - Expected file size: 51793194 02:28:10 INFO - Obtained file size: 51793194 02:28:25 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 02:28:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:28:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:28:25 INFO - Fetch https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:28:27 INFO - Expected file size: 51793194 02:28:27 INFO - Obtained file size: 51793194 02:28:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:28:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:28:42 INFO - Fetch https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:28:43 INFO - Expected file size: 14404424 02:28:43 INFO - Obtained file size: 14404424 02:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:43 INFO - trying https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:28:43 INFO - Downloading https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:28:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/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 02:28:46 INFO - Downloaded 74737776 bytes. 02:28:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:28:46 INFO - mkdir: /builds/slave/test/properties 02:28:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:46 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:46 INFO - Contents: 02:28:46 INFO - build_url:https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - Running post-action listener: set_extra_try_arguments 02:28:46 INFO - [mozharness: 2017-04-13 09:28:46.751674Z] Finished download-and-extract step (success) 02:28:46 INFO - [mozharness: 2017-04-13 09:28:46.751836Z] Running populate-webroot step. 02:28:46 INFO - Running pre-action listener: _resource_record_pre_action 02:28:46 INFO - Running main action method: populate_webroot 02:28:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:46 INFO - '--tppagecycles', 02:28:46 INFO - '7'], 02:28:46 INFO - 'tests': ['tsvgm']}}, 02:28:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['damp', 'tps']}, 02:28:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'tests': ['damp', 'tps']}, 02:28:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['dromaeo_dom']}, 02:28:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['basic_compositor_video']}, 02:28:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:28:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tsvgx', 02:28:46 INFO - 'tsvgr_opacity', 02:28:46 INFO - 'tart', 02:28:46 INFO - 'tscrollx', 02:28:46 INFO - 'cart', 02:28:46 INFO - 'tsvg_static']}, 02:28:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:46 INFO - 'tsvgr_opacity', 02:28:46 INFO - 'tart', 02:28:46 INFO - 'tscrollx', 02:28:46 INFO - 'cart', 02:28:46 INFO - 'tsvg_static']}, 02:28:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tp5o']}, 02:28:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:28:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s', 02:28:46 INFO - '--xperf_path', 02:28:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:46 INFO - 'tests': ['tp5n']}, 02:28:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:28:46 INFO - 'talos_options': ['--xperf_path', 02:28:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:46 INFO - 'tests': ['tp5n']}}, 02:28:46 INFO - 'talos.zip': {'path': '', 02:28:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - [mozharness: 2017-04-13 09:28:46.770092Z] Finished populate-webroot step (success) 02:28:46 INFO - [mozharness: 2017-04-13 09:28:46.770208Z] Running create-virtualenv step. 02:28:46 INFO - Running pre-action listener: _resource_record_pre_action 02:28:46 INFO - Running main action method: create_virtualenv 02:28:46 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:46 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 02:28:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:46 INFO - Using real prefix '/tools/python27' 02:28:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:47 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:50 INFO - Installing pip.................done. 02:28:50 INFO - Return code: 0 02:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:28:50 INFO - Reading from file tmpfile_stdout 02:28:50 INFO - Output received: 02:28:50 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) 02:28:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:28: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', 'pip>=1.5'] in /builds/slave/test/build 02:28: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 pip>=1.5 02:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:50 INFO - 'HOME': '/Users/cltbld', 02:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:50 INFO - 'LOGNAME': 'cltbld', 02:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:50 INFO - 'MOZ_NO_REMOTE': '1', 02:28:50 INFO - 'NO_EM_RESTART': '1', 02:28:50 INFO - 'PAGER': '/bin/cat', 02:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:50 INFO - 'PWD': '/builds/slave/test', 02:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:50 INFO - 'SHELL': '/bin/bash', 02:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:28:50 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:50 INFO - 'USER': 'cltbld', 02:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:50 INFO - 'XPC_FLAGS': '0x0', 02:28:50 INFO - 'XPC_SERVICE_NAME': '0', 02:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:50 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 02:28:50 INFO - Cleaning up... 02:28:51 INFO - Return code: 0 02:28:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:28:51 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')]} 02:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:51 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')]} 02:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:51 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:28:51 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 02:28:51 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 02:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:51 INFO - 'HOME': '/Users/cltbld', 02:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:51 INFO - 'LOGNAME': 'cltbld', 02:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:51 INFO - 'MOZ_NO_REMOTE': '1', 02:28:51 INFO - 'NO_EM_RESTART': '1', 02:28:51 INFO - 'PAGER': '/bin/cat', 02:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:51 INFO - 'PWD': '/builds/slave/test', 02:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:51 INFO - 'SHELL': '/bin/bash', 02:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:28:51 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:51 INFO - 'USER': 'cltbld', 02:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:51 INFO - 'XPC_FLAGS': '0x0', 02:28:51 INFO - 'XPC_SERVICE_NAME': '0', 02:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:51 INFO - Downloading/unpacking psutil>=3.1.1 02:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:54 INFO - Installing collected packages: psutil 02:28:54 INFO - Running setup.py install for psutil 02:28:54 INFO - building 'psutil._psutil_osx' extension 02:28:54 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 02:28:55 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 02:28:55 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 02:28:55 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 02:28:55 INFO - building 'psutil._psutil_posix' extension 02:28:55 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 02:28:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:55 INFO - ^ 02:28:55 INFO - 1 warning generated. 02:28:55 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 02:28:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:55 INFO - Successfully installed psutil 02:28:55 INFO - Cleaning up... 02:28:56 INFO - Return code: 0 02:28:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:56 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:28:56 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 02:28:56 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 02:28:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:56 INFO - 'HOME': '/Users/cltbld', 02:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:56 INFO - 'LOGNAME': 'cltbld', 02:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:56 INFO - 'MOZ_NO_REMOTE': '1', 02:28:56 INFO - 'NO_EM_RESTART': '1', 02:28:56 INFO - 'PAGER': '/bin/cat', 02:28:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:56 INFO - 'PWD': '/builds/slave/test', 02:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:56 INFO - 'SHELL': '/bin/bash', 02:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:28:56 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:56 INFO - 'USER': 'cltbld', 02:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:56 INFO - 'XPC_FLAGS': '0x0', 02:28:56 INFO - 'XPC_SERVICE_NAME': '0', 02:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:00 INFO - Installing collected packages: mozsystemmonitor 02:29:00 INFO - Running setup.py install for mozsystemmonitor 02:29:00 INFO - Successfully installed mozsystemmonitor 02:29:00 INFO - Cleaning up... 02:29:00 INFO - Return code: 0 02:29:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:00 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:00 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 02:29:00 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 02:29:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:00 INFO - 'HOME': '/Users/cltbld', 02:29:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:00 INFO - 'LOGNAME': 'cltbld', 02:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:00 INFO - 'MOZ_NO_REMOTE': '1', 02:29:00 INFO - 'NO_EM_RESTART': '1', 02:29:00 INFO - 'PAGER': '/bin/cat', 02:29:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:00 INFO - 'PWD': '/builds/slave/test', 02:29:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:00 INFO - 'SHELL': '/bin/bash', 02:29:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:00 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:00 INFO - 'USER': 'cltbld', 02:29:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:00 INFO - 'XPC_FLAGS': '0x0', 02:29:00 INFO - 'XPC_SERVICE_NAME': '0', 02:29:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:00 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:00 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 02:29:00 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 02:29:00 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 02:29:00 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 02:29:00 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 02:29:00 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 02:29:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:03 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 02:29:03 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 02:29:03 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 02:29:03 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 02:29:03 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 02:29:03 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 02:29:03 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:03 INFO - warning: no files found matching '*.txt' 02:29:03 INFO - no previously-included directories found matching 'build' 02:29:03 INFO - no previously-included directories found matching 'dist' 02:29:03 INFO - no previously-included directories found matching '.git*' 02:29:03 INFO - Installing collected packages: jsonschema, functools32 02:29:03 INFO - Running setup.py install for functools32 02:29:04 INFO - warning: no files found matching '*.txt' 02:29:04 INFO - no previously-included directories found matching 'build' 02:29:04 INFO - no previously-included directories found matching 'dist' 02:29:04 INFO - no previously-included directories found matching '.git*' 02:29:04 INFO - Successfully installed jsonschema functools32 02:29:04 INFO - Cleaning up... 02:29:04 INFO - Return code: 0 02:29:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29:04 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')]} 02:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:04 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')]} 02:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:04 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:04 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 02:29:04 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 02:29:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:04 INFO - 'HOME': '/Users/cltbld', 02:29:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:04 INFO - 'LOGNAME': 'cltbld', 02:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:04 INFO - 'MOZ_NO_REMOTE': '1', 02:29:04 INFO - 'NO_EM_RESTART': '1', 02:29:04 INFO - 'PAGER': '/bin/cat', 02:29:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:04 INFO - 'PWD': '/builds/slave/test', 02:29:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:04 INFO - 'SHELL': '/bin/bash', 02:29:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:04 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:04 INFO - 'USER': 'cltbld', 02:29:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:04 INFO - 'XPC_FLAGS': '0x0', 02:29:04 INFO - 'XPC_SERVICE_NAME': '0', 02:29:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:04 INFO - Cleaning up... 02:29:04 INFO - Return code: 0 02:29:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:04 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')]} 02:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:04 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')]} 02:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:04 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:04 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 02:29:04 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 02:29:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:04 INFO - 'HOME': '/Users/cltbld', 02:29:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:04 INFO - 'LOGNAME': 'cltbld', 02:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:04 INFO - 'MOZ_NO_REMOTE': '1', 02:29:04 INFO - 'NO_EM_RESTART': '1', 02:29:04 INFO - 'PAGER': '/bin/cat', 02:29:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:04 INFO - 'PWD': '/builds/slave/test', 02:29:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:04 INFO - 'SHELL': '/bin/bash', 02:29:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:04 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:04 INFO - 'USER': 'cltbld', 02:29:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:04 INFO - 'XPC_FLAGS': '0x0', 02:29:04 INFO - 'XPC_SERVICE_NAME': '0', 02:29:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:04 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:04 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 02:29:04 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 02:29:04 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 02:29:04 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 02:29:04 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 02:29:04 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 02:29:07 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 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 02:29:08 INFO - Downloading docopt-0.6.1.tar.gz 02:29:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:08 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:08 INFO - Running setup.py install for blobuploader 02:29:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:08 INFO - Running setup.py install for requests 02:29:09 INFO - Running setup.py install for docopt 02:29:09 INFO - Successfully installed blobuploader requests docopt 02:29:09 INFO - Cleaning up... 02:29:09 INFO - Return code: 0 02:29:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:09 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:09 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 02:29:09 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 02:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:09 INFO - 'HOME': '/Users/cltbld', 02:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:09 INFO - 'LOGNAME': 'cltbld', 02:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:09 INFO - 'MOZ_NO_REMOTE': '1', 02:29:09 INFO - 'NO_EM_RESTART': '1', 02:29:09 INFO - 'PAGER': '/bin/cat', 02:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:09 INFO - 'PWD': '/builds/slave/test', 02:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:09 INFO - 'SHELL': '/bin/bash', 02:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:09 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:09 INFO - 'USER': 'cltbld', 02:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:09 INFO - 'XPC_FLAGS': '0x0', 02:29:09 INFO - 'XPC_SERVICE_NAME': '0', 02:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-MYyX_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-vdchiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-sCLS25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-nrQw0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-wgESZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-cwQ4w3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-9TMkbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-7SqwkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-bOL2mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-2ctZD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-LutjI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-swaWqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-TfCveS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-oyQ3cB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-oh9SDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-hEoygu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-R4i6Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:11 INFO - Running setup.py install for manifestparser 02:29:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Running setup.py install for mozcrash 02:29:11 INFO - Running setup.py install for mozdebug 02:29:11 INFO - Running setup.py install for mozdevice 02:29:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Running setup.py install for mozfile 02:29:12 INFO - Running setup.py install for mozhttpd 02:29:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Running setup.py install for mozinfo 02:29:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Running setup.py install for mozInstall 02:29:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:12 INFO - Running setup.py install for mozleak 02:29:12 INFO - Running setup.py install for mozlog 02:29:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Running setup.py install for moznetwork 02:29:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Running setup.py install for mozprocess 02:29:13 INFO - Running setup.py install for mozprofile 02:29:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Running setup.py install for mozrunner 02:29:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:13 INFO - Running setup.py install for mozscreenshot 02:29:13 INFO - Running setup.py install for moztest 02:29:14 INFO - Running setup.py install for mozversion 02:29:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:14 INFO - Cleaning up... 02:29:14 INFO - Return code: 0 02:29:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:14 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')]} 02:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:14 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')]} 02:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:14 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:14 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 02:29:14 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 02:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:14 INFO - 'HOME': '/Users/cltbld', 02:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:14 INFO - 'LOGNAME': 'cltbld', 02:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:14 INFO - 'MOZ_NO_REMOTE': '1', 02:29:14 INFO - 'NO_EM_RESTART': '1', 02:29:14 INFO - 'PAGER': '/bin/cat', 02:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:14 INFO - 'PWD': '/builds/slave/test', 02:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:14 INFO - 'SHELL': '/bin/bash', 02:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:14 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:14 INFO - 'USER': 'cltbld', 02:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:14 INFO - 'XPC_FLAGS': '0x0', 02:29:14 INFO - 'XPC_SERVICE_NAME': '0', 02:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-IDbjsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:14 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)) 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Vbhsco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:14 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)) 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-uN0Cmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:14 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)) 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-qsNpgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:14 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)) 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-A7LFyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:14 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)) 02:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:14 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-F7Xln8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-HbsBrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-AdhVD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-hw4CKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-g8XxZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-WGbUah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-jZFxtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-tJRTwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-3Td6un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:15 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)) 02:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:15 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-3TI2w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:16 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)) 02:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-FEnNmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:16 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)) 02:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-FoS370-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:16 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)) 02:29:16 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)) 02:29:16 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)) 02:29:16 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)) 02:29:16 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)) 02:29:16 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)) 02:29:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:18 INFO - Downloading blessings-1.6.tar.gz 02:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:18 INFO - Installing collected packages: blessings 02:29:18 INFO - Running setup.py install for blessings 02:29:19 INFO - Successfully installed blessings 02:29:19 INFO - Cleaning up... 02:29:19 INFO - Return code: 0 02:29:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:19 INFO - Reading from file tmpfile_stdout 02:29:19 INFO - Current package versions: 02:29:19 INFO - blessings == 1.6 02:29:19 INFO - blobuploader == 1.2.4 02:29:19 INFO - docopt == 0.6.1 02:29:19 INFO - functools32 == 3.2.3-2 02:29:19 INFO - jsonschema == 2.5.1 02:29:19 INFO - manifestparser == 1.1 02:29:19 INFO - mozInstall == 1.12 02:29:19 INFO - mozcrash == 1.0 02:29:19 INFO - mozdebug == 0.1 02:29:19 INFO - mozdevice == 0.49 02:29:19 INFO - mozfile == 1.2 02:29:19 INFO - mozhttpd == 0.7 02:29:19 INFO - mozinfo == 0.9 02:29:19 INFO - mozleak == 0.1 02:29:19 INFO - mozlog == 3.4 02:29:19 INFO - moznetwork == 0.27 02:29:19 INFO - mozprocess == 0.25 02:29:19 INFO - mozprofile == 0.28 02:29:19 INFO - mozrunner == 6.13 02:29:19 INFO - mozscreenshot == 0.1 02:29:19 INFO - mozsystemmonitor == 0.3 02:29:19 INFO - moztest == 0.8 02:29:19 INFO - mozversion == 1.4 02:29:19 INFO - psutil == 3.1.1 02:29:19 INFO - requests == 1.2.3 02:29:19 INFO - wsgiref == 0.1.2 02:29:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 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 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:19 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 02:29:19 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 02:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:19 INFO - 'HOME': '/Users/cltbld', 02:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:19 INFO - 'LOGNAME': 'cltbld', 02:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:19 INFO - 'MOZ_NO_REMOTE': '1', 02:29:19 INFO - 'NO_EM_RESTART': '1', 02:29:19 INFO - 'PAGER': '/bin/cat', 02:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:19 INFO - 'PWD': '/builds/slave/test', 02:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:19 INFO - 'SHELL': '/bin/bash', 02:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:19 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:19 INFO - 'USER': 'cltbld', 02:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:19 INFO - 'XPC_FLAGS': '0x0', 02:29:19 INFO - 'XPC_SERVICE_NAME': '0', 02:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 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)) 02:29:19 INFO - Cleaning up... 02:29:19 INFO - Return code: 0 02:29:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e696b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e699768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1635c69a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, '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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 02:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:19 INFO - 'HOME': '/Users/cltbld', 02:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:19 INFO - 'LOGNAME': 'cltbld', 02:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:19 INFO - 'MOZ_NO_REMOTE': '1', 02:29:19 INFO - 'NO_EM_RESTART': '1', 02:29:19 INFO - 'PAGER': '/bin/cat', 02:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:19 INFO - 'PWD': '/builds/slave/test', 02:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:19 INFO - 'SHELL': '/bin/bash', 02:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:19 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:19 INFO - 'USER': 'cltbld', 02:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:19 INFO - 'XPC_FLAGS': '0x0', 02:29:19 INFO - 'XPC_SERVICE_NAME': '0', 02:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:20 INFO - Cleaning up... 02:29:20 INFO - Return code: 0 02:29:20 INFO - Running post-action listener: _resource_record_post_action 02:29:20 INFO - Running post-action listener: _start_resource_monitoring 02:29:20 INFO - Starting resource monitoring. 02:29:20 INFO - [mozharness: 2017-04-13 09:29:20.086757Z] Finished create-virtualenv step (success) 02:29:20 INFO - [mozharness: 2017-04-13 09:29:20.087736Z] Running install step. 02:29:20 INFO - Running pre-action listener: _resource_record_pre_action 02:29:20 INFO - Running main action method: install 02:29:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:20 INFO - Reading from file tmpfile_stdout 02:29:20 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:20 INFO - Reading from file tmpfile_stdout 02:29:20 INFO - Output received: 02:29:20 INFO - Usage: mozinstall [options] installer 02:29:20 INFO - Options: 02:29:20 INFO - -h, --help show this help message and exit 02:29:20 INFO - -d DEST, --destination=DEST 02:29:20 INFO - Directory to install application into. [default: 02:29:20 INFO - "/builds/slave/test"] 02:29:20 INFO - --app=APP Application being installed. [default: firefox] 02:29:20 INFO - mkdir: /builds/slave/test/build/application 02:29:20 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'] 02:29:20 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 02:29:40 INFO - Reading from file tmpfile_stdout 02:29:40 INFO - Output received: 02:29:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:29:40 INFO - Running post-action listener: _resource_record_post_action 02:29:40 INFO - [mozharness: 2017-04-13 09:29:40.851670Z] Finished install step (success) 02:29:40 INFO - [mozharness: 2017-04-13 09:29:40.851846Z] Running run-tests step. 02:29:40 INFO - Running pre-action listener: _resource_record_pre_action 02:29:40 INFO - Running main action method: run_tests 02:29:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:40 INFO - 2017-04-13 02:29:40.866 screenresolution[1623:7448] starting screenresolution argv=screenresolution get 02:29:40 INFO - 2017-04-13 02:29:40.868 screenresolution[1623:7448] Display 0: 1600x1200x32@60 02:29:40 INFO - 2017-04-13 02:29:40.874 screenresolution[1624:7450] starting screenresolution argv=screenresolution list 02:29:40 INFO - Available Modes on Display 0 02:29:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:41 INFO - Intel Iris: 02:29:41 INFO - Chipset Model: Intel Iris 02:29:41 INFO - Type: GPU 02:29:41 INFO - Bus: Built-In 02:29:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:41 INFO - Vendor: Intel (0x8086) 02:29:41 INFO - Device ID: 0x0a2e 02:29:41 INFO - Revision ID: 0x0009 02:29:41 INFO - Displays: 02:29:41 INFO - Display: 02:29:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:41 INFO - Display Serial Number: 1600x1200 60 02:29:41 INFO - Main Display: Yes 02:29:41 INFO - Mirror: Off 02:29:41 INFO - Online: Yes 02:29:41 INFO - Rotation: Supported 02:29:41 INFO - Return code: 0 02:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:41 INFO - Python 2.7.3 02:29:41 INFO - Return code: 0 02:29:41 INFO - grabbing minidump binary from tooltool 02:29:41 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')]} 02:29:41 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 0x10e474d30>, '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 0x10e39bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:41 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 02:29:41 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 02:29:41 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 02:29:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:41 INFO - Return code: 0 02:29:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:41 INFO - ENV: RUST_BACKTRACE is now 1 02:29:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:41 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/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 02:29:41 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/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --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 02:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pbn4RfXPAt/Render', 02:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:41 INFO - 'HOME': '/Users/cltbld', 02:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:41 INFO - 'LOGNAME': 'cltbld', 02:29:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:41 INFO - 'MOZ_NO_REMOTE': '1', 02:29:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:41 INFO - 'NO_EM_RESTART': '1', 02:29:41 INFO - 'PAGER': '/bin/cat', 02:29:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:41 INFO - 'PWD': '/builds/slave/test', 02:29:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:41 INFO - 'RUST_BACKTRACE': '1', 02:29:41 INFO - 'SHELL': '/bin/bash', 02:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NLa9N7gpG1/Listeners', 02:29:41 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 02:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:41 INFO - 'USER': 'cltbld', 02:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:41 INFO - 'XPC_FLAGS': '0x0', 02:29:41 INFO - 'XPC_SERVICE_NAME': '0', 02:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:41 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/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 02:29:41 INFO - mozversion application_buildid: 20170413004024 02:29:41 INFO - mozversion application_changeset: 4c78da5f6ddacce5332ead124aa734635fc83d8f 02:29:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:41 INFO - mozversion application_name: Firefox 02:29:41 INFO - mozversion application_remotingname: firefox-dev 02:29:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:41 INFO - mozversion application_vendor: Mozilla 02:29:41 INFO - mozversion application_version: 54.0a2 02:29:41 INFO - mozversion platform_buildid: 20170413004024 02:29:41 INFO - mozversion platform_changeset: 4c78da5f6ddacce5332ead124aa734635fc83d8f 02:29:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:41 INFO - mozversion platform_version: 54.0a2 02:29:41 INFO - using testdate: 1492075781 02:29:41 INFO - actual date: 1492075781 02:29:41 INFO - starting webserver on 'localhost:49236' 02:29:41 INFO - SUITE-START | Running 2 tests 02:29:41 INFO - TEST-START | dromaeo_css 02:29:41 INFO - operating with platform_type : mac_ 02:29:41 INFO - Initialising browser for dromaeo_css test... 02:29:41 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpoUpyWr/profile http://localhost:49236/getInfo.html) 02:29:44 INFO - PROCESS | 1634 | 2017-04-13 02:29:44.359 plugin-container[1637:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x942f, name = 'com.apple.tsm.portname' 02:29:44 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:44 INFO - PROCESS | 1634 | 2017-04-13 02:29:44.360 plugin-container[1637:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x5017, name = 'com.apple.CFPasteboardClient' 02:29:44 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:44 INFO - PROCESS | 1634 | 2017-04-13 02:29:44.360 plugin-container[1637:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:44 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 02:29:44 INFO - PROCESS | 1634 | colorDepth:24 02:29:44 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 02:29:44 INFO - PROCESS | 1634 | __metrics 02:29:44 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:29:45 INFO - TEST-INFO | 1634: exit 0 02:29:45 INFO - Browser initialized. 02:29:45 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:45 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpoUpyWr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:47 INFO - PROCESS | 1638 | 2017-04-13 02:29:47.969 plugin-container[1639:7772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:29:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:47 INFO - PROCESS | 1638 | 2017-04-13 02:29:47.969 plugin-container[1639:7772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:29:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:47 INFO - PROCESS | 1638 | 2017-04-13 02:29:47.969 plugin-container[1639:7772] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:57 INFO - PROCESS | 1638 | RSS: Main: 221224960 02:29:57 INFO - PROCESS | 1638 | 02:33:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 02:33:03 INFO - PROCESS | 1638 | RSS: Main: 238678016 02:33:03 INFO - PROCESS | 1638 | 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:34:45 INFO - PROCESS | 1638 | 02:34:45 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:34:45 INFO - PROCESS | 1638 | Full stack: 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:34:45 INFO - PROCESS | 1638 | 02:34:45 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:34:45 INFO - PROCESS | 1638 | Full stack: 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:34:45 INFO - PROCESS | 1638 | 02:34:45 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:34:45 INFO - PROCESS | 1638 | Full stack: 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | ************************* 02:34:45 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:34:45 INFO - PROCESS | 1638 | 02:34:45 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:34:45 INFO - PROCESS | 1638 | Full stack: 02:34:45 INFO - PROCESS | 1638 | ************************* 02:36:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 02:36:06 INFO - PROCESS | 1638 | RSS: Main: 245694464 02:36:06 INFO - PROCESS | 1638 | 02:39:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 02:39:08 INFO - PROCESS | 1638 | RSS: Main: 254226432 02:39:08 INFO - PROCESS | 1638 | 02:42:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 02:42:11 INFO - PROCESS | 1638 | RSS: Main: 251809792 02:42:11 INFO - PROCESS | 1638 | 02:45:14 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 02:45:14 INFO - PROCESS | 1638 | RSS: Main: 256720896 02:45:14 INFO - PROCESS | 1638 | 02:48:17 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 02:48:18 INFO - PROCESS | 1638 | RSS: Main: 253034496 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | __start_tp_report 02:48:18 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 02:48:18 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 02:48:18 INFO - PROCESS | 1638 | |i|pagename|runs| 02:48:18 INFO - PROCESS | 1638 | |0;dojo.html;2712.40327128032;3133.15404840693;3489.040513858688;3491.0748651607328;3495.947560786589;6035.879282414349;6076.574639775217;6086.30007549132;6130.540209484284;6189.783357582485;6096.420840020202;6115.266168059839;6162.414570615795;6163.322034576193;6192.81421557354;5443.2107344435035;5471.179323101546;5518.806841760539;5589.413111623282;5616.859578510529;5577.69322687254;5581.972090139564;5582.665040097587;5588.776448942058;5591.692456914868;5579.46995035473;5584.776608935637;5592.217089607459;5597.104463285875;5610.747516361753;5347.0909945309395;5356.250124982505;5402.108652072403;5578.470045345685;5587.804426845061;4461.42001539801;5453.563714902838;5464.1257398816;5469.234307196995;5505.944940550583;6195.783147589857;6250.593711408789;6261.311255761908;6263.906041409383;6266.937330626681;6301.464375528086;6354.078658594524;6387.552871299011;6430.839229019265;6458.838529036811;6258.092576576371;6302.842428939267;6305.716242769086;6312.652804095819;6374.458171055466;6179.3202747697715;6244.344343843876;6248.062790581449;6271.623702577815;6304.211973503312;6655.101561289261;6686.1642294713165;6722.630255336004;6762.053312536151;6765.560238584476;6216.285127210338;6247.656378899204;6287.842803929847;6354.650494222862;6356.904646430307;6391.872162556723;6423.518236132309;6452.9677351612945;6466.385693359123;6468.8059358219325;6350.682465876687;6400.743970241231;6451.451626611697;6473.579217350857;6511.479081673455;6212.316645169028;6329.145565348619;6400.871982560323;6407.583507072025;6429.9678501607195;5139.7687104080405;5150.40770311419;5182.6372153949615;5187;5207.062728708793;3741.8316175772156;4537.001859590885;4599.5400459953735;4639.420072490939;4672.6261899047995;6203;6250.062490626351;6258.123862659231;6272.780452684134;6302.936970630327;6560.704768285439;6564.934350656527;6612.206535215709;6617.768378106839;6649.202095748541;6015.879682406415;6101.786437474667;6108.7670647404;6121.96939015311;6135.692037377249;5743.85640358986;5767.653940763567;5770.105633626795;5807.738651760681;5809.448102430262;6769.187697476433;6787.287334829772;6798.150231221098;6811.591304521547;6817.079694241214;5539.529140023144;5564.831385409109;6244.68776561161;6330.3669633036325;6485.546011779319;5286.709230992333;6683.097781799394;6692.431143352869;6722.159730033746;6748.291429399842;6393.808185754435;6431.903521447276;6456.903146452713;6465.353464653497;6465.967670161619;6218.440340369208;6337.588056776295;6490.253620833664;6571.211454625475;6584.078229048033;6228.564000520101;6238.438540531374;6244.843878903063;6346.555741098079;6389.073584330339;6323.20959880015;6337.809865703852;6343.651099189589;6345.587536810092;6364.299927007933;6148.877022459436;6185.876282474415;6249.281332646804;6276.529260305404;6296.496280297473;4911.410630724193;5576.275084239023;6163.907541386793;6202.689865506797;6270.529710271657;6218.875622487615;6242.5942313749465;6252.968735156477;6258;6288.0882272069375 02:48:18 INFO - PROCESS | 1638 | |1;ext.html;576.7779404929104;793.2067932067931;814.3566582399905;945.5461378538306;949.7957939043105;37227.81386093069;38340.424893626616;39890;39906;39941.99999999996;30009.849950750217;38335.04162395941;38714.612853871455;38774.418383724194;38782.03044923878;7789.104253010905;7913.564753938531;7924.881126783102;10468.842967355497;10751.784964300708;10504.2647014709;10662.733431664232;10669.359838409682;10671.946640266826;10685.732856678567;10657.147428205724;10688.358698478116;10708.143348532138;10745.946270268638;10765.407902565355;10638.255322127454;10644.787104257948;10675.946620266926;10716.41059741714;10716.946415267912;10380;10435.843462348072;10451.581936722521;10457.267991240617;10467.267291289612;33930.32139357224;34037.489437658456;34052.31895362079;34067.488987664954;34104.48843267353;3696.722745794076;3721.8325175366904;3755.2489502099415;3766.8493260269556;3811.27585758705;24737.010519579195;24770.25689229326;24994.875025624937;25504;26238;2701.7977000234705;2728.8089833711647;3009.50343193375;3133.984330078335;3239.7732158748895;324050;329349;368586;368690;423184;51420.48579514232;51665.741671291406;54650.453495464535;54738.63153421196;54833.72583137138;41452.79273603613;56909.14636280459;57081.714591427604;57516.71241643765;57683;15068.246587670572;15084.472043478645;15091.943563950637;15099;15104.471343503144;12908.419121139572;12918.612441626767;12953.870461295455;12961.41673624689;12991.480340786451;11915.404229788475;12617.495300188073;13533.39099740514;13541.187528748307;13615.31923403826;6082.300535438393;6096.3903609639565;6104.053871649901;6117.541184411098;6138;1091.24158709696;1093.4150229627112;1103.1119948441421;1104.4091411095026;1151.2631915574038;25938.22185334443;26401;26524.46951060968;26699.33251668724;27419.588706169423;1010.5654568535454;1193.731410432663;1213.120487646467;1218.2386008744534;1246.4266437438698;11075.72310692239;13580.185188888698;13943.16341019542;13956.651083722989;13981.580552583438;489240;498439;500311;501388;501574;56685;56911.71544142253;57616.4238357611;57949.420505794405;57997;1401.0962928910797;1451.2888684544498;1454.461849115834;1464.6191990082655;1469.7354476194173;21374.679379809626;25440.491190175722;25818.838152282817;26243.60634590521;26365;44861;45400;47412.2888156671;49105;49257.2611410822;512023;513831;513928;517191;518674;26101.477970440865;26276.474470510864;30734.385312294075;31311.530327044657;32290.19274518062;38149.4277585842;38232;38537.421938671505;38910;39331.410028850165;27012.72987270102;27864.86067569649;27912.44175116527;27922.743476544012;28304.85847570749;25842;27702.445951080463;28479.715202847707;28530.714692852805;28561;31738.682613173572;32672;32781;32874.17814554655;32891;27782.722172778012;28295.15114546567;28322.575161372184;28694.56958145671;29203.561946571244;18404.723929140804;20252.392428227176;20405.897970510054;22730.545389092455;23335.53328933378 02:48:18 INFO - PROCESS | 1638 | |2;jquery.html;355.96796288334053;383.243094887597;403.0709215258829;403.9698768141241;410.2785575469662;15533.53399398017;19819.405417837454;20815.583688326224;21190.258340958088;21207.68188477174;19088.904555477202;19282.843029418244;20788.896055519737;21190;21501.892490537524;5992.25096862891;6305;6394.232692076957;6396.968015159918;6411.198600174978;6288.402601752826;6382.20222472191;6406.871862562769;6408.58344207625;6430.196225471816;6423.871522569546;6431.42117209451;6434.646094464802;6440.484761219114;6450.548461607689;6212.875742485147;6376.9681151594405;6409.615423074607;6505.837354066139;6520.152380190592;6325.936740632581;6329.2721337045905;6338.492920566389;6339.175907132089;6802.727890884359;25677.871610642014;26040.609390859154;26142.86928565364;26446;26725.331866703487;3207.5188721691698;3272.5091236314506;3275.8034517929;3279.4916787897914;3295.275039491332;14951.327190276355;16501.33994640213;16529.752053719203;16644.750328745078;16910.492685219462;2013.2752209204675;2039.2964427272568;2042.2954080842085;2043.4482689673705;2044.2742826296599;94820;104819;108223;151146.24426877795;158629;20253.79746202519;20526.38420847377;21183;21202.893985529972;21268.893655531625;31692.89074882368;32026.67973320237;32555.51166732501;32636.83681581577;32762.017139485855;10324.174066074696;10467.685969420929;10720.35677859331;10732.731681707864;10806.51370688321;7351.41188704913;8867.113288671082;8869.068747781519;8875.467471951575;9018.819623607491;9627.277954153467;9834.459104749165;9843.75390615226;9895.851562226573;9930.851037234448;3314.6022477302877;3390.8135052572347;4102.830693252408;4118.649914757219;4125.566815484331;791.5132193700832;847.3094428041127;887.0597167002998;891.733738091911;913.5340976102103;21528;22148.335549933527;22158.556828863333;22233.555328893333;22249.777502225093;895.3777124898133;907.650554536495;913.397157875812;917.1287277086794;919.2232563483846;9380.718578442658;11143.888561114447;11447.771044579229;11524.769504609862;11548.076153907667;744384;781529;795065;796267;807183;154796.22601887144;155267;155567.22216388845;160669;161435;1172.302480024384;1176.5352685689104;1193.761247750436;1194.9940250298694;1203.2419575667273;14918.477853275082;18022.639547209244;18847.15187816524;19226.711599325743;19517.8048219516;35558;54658;58459.538511537554;62228;64422.35577644163;80739.59630201811;84665.57667211625;87499;95510.52244738732;98945;7606.619669016416;9660.37207581505;10912.727181820517;10919.508622112184;11175.552977880794;12244.510219591117;13087.869121308666;13135.802962955373;13171.868281317064;13573.728525429633;8413.789655258668;11216.66350009501;11516.366599837089;11766.52933882635;11786.528538858693;9306.32486152499;10535.841962370723;10587.523561439908;10626;10667.893321067;8007.879881801661;11385.373804440835;11598.07215422781;11599;11984.880151198377;9674.371165874196;11517.193796434169;11693.824592631288;11809;11816.40917954116;11133.276337038067;11286.604968826052;11409;11547.018503427304;11676.065914726632 02:48:18 INFO - PROCESS | 1638 | |3;mootools.html;1354.5123755448037;1454.7963285140077;1531.402752926359;1546.5515000649812;1554.2617256803017;10634.255602107856;11147.219694621379;11326.546938122465;11345.205835591512;11442.14183936206;9065.365424420293;10718.571257149724;10892.782144357108;10896.40069796161;10896.836547451774;2358.841659881207;2385.1651921827397;2391.0674836813655;2394.221877889684;2413.650020746991;2338.4037070546988;2413.046846495633;2421.987890060547;2427.2960841356075;2438.426969662128;2353.4587044979667;2421.491486787768;2425.708914930211;2437.512497500507;2439.402346425128;2352.3766201956323;2383.2254517281954;2391.964120538193;2393.7845593896545;2422.8304018718695;2377.191754803358;2392.294273189413;2406.3984003999;2417.9274621761374;2420.636904464327;8890.33322500815;9950.154236889874;11027.889721102767;11146.275492092987;11170.162237832114;5766.279215098113;5828.795992140297;5921.171036054956;5935.465808077251;5954.612950158269;2976.955345669817;3231.74146068314;3361.1429085583018;3527.5590551181103;3585.229175727205;1124.269225003746;1136.9147313951405;1148.110214583696;1155.2375432214783;1167.773837470661;11111.055560277386;13650.044496885223;14711.63220919475;15539.844601553952;15593.688126237525;3921.8431262749173;4060.3503439449546;4557.840475583339;4754.738489383117;4757.524247575215;9770.706878793648;11750.412479375997;11822.467988940518;11911.583094591648;11925.582604608797;5682.54539636828;5770.6249093808765;5778.624389414758;5792.304923409133;5806.796762113306;5173.379194496684;5243.895122097613;5246.134387826042;5264.42091369949;5281.524662780368;3191.3138675184946;3249.6100467943998;3859.1895701902354;3967.0677390813134;3968.722189446711;1024.7950409918046;1026.6150193677372;1027.984580231297;1037.3620223562605;1049.197364016528;785.3010820369875;796.7729197178892;803.7548547692893;805.6414895371504;811.8011087283535;6487.61074335532;6494.415502604788;6621.503387245976;6635.2037755469655;6703.363180497845;747.9887801682979;778.3267473635378;947.2043483473915;953.1945506047379;956.5026186383042;4038.757674539478;4056.9594304057173;4058.9594104059174;4173.603507666767;4182.079942412725;13872.167669939836;14006.509772157926;14515.92742036283;14525.128492290496;14579.416823326948;14064;14313.28433578317;14416.495422660366;14423.42306307757;14457.204853732936;8788.736337909744;10404.167666586654;10524.105451036748;10611.628592999208;10620.893791062144;6350.206224221973;6756.7972361733155;6815.852578581244;6880.311968803079;6929.099217101744;11392.373419462008;12081.637550873487;12089.516419343128;12112;12129.150959432754;11991.940040299743;12114.818277726017;12118.394080295773;12192.939035304767;12285.324307163191;8361.247487726134;8361.95819020901;8378.53918034514;8423.620937057969;8451.1548845115;10185.236107291834;10327.122194613543;10371.740706482398;10377.584896604052;10378.27352085377;8609.440386374865;8755.12448755145;8809.207171354608;8837;8882.866756998521;8611.784705382415;8625.568721564023;8636.82726345457;8670.78323041929;8815.426997245158;7853.568053757098;8009.679612815422;8016.39877009215;8019.679212831421;8046.396520261121;5711.800086997101;5719.256496655408;5729.341125770589;5937.2578427696535;5957;5979.133025711334;6036.849078773066;6074.51403887679;6243.96878015607;6300.0234963580715 02:48:18 INFO - PROCESS | 1638 | |4;prototype.html;314.872476646958;340.1699852360241;365.9377905756021;372.61806648185626;381.99236015279695;4846.200376937801;5108.8978220435565;5386.949544838758;5424.5117939385445;5444.2378067070595;4432.889177770558;4483.461984561857;4854.4174699036075;5135.743212839362;5233.8953220935555;3913.2956067907767;4198.307279298912;4337.63130133939;4347.652187824967;4379.75911324877;3596.92806143877;3934.803259837011;4315.71947823392;4374.7156434831795;4448.599626033656;3574.4280915053596;3754.6808521275725;4353.804078816461;4380.846670366538;4439.8446054388105;3883.805809709503;3986.86045988389;4244.257254980397;4348.499922508889;4400.493943196525;3497.7376696747847;3828.2007456495403;3935.3900145477496;3983.701222408302;3994.800259986989;4424.628365206796;4840.733759643218;5145;5208.817691380784;5215.191645294985;3989.740666856645;4182.811773470201;4427.977860110711;4693.788779504896;4907.411110666707;4815.9927305770025;5408.59435542332;5552.806146678484;5559.8888022239735;5587.569407307834;4371.825126994916;4431.800568974371;4472.971216620163;4521.886952826173;4525.343825145368;5689.715514224314;5828.5628577856405;5831.591788574844;6001.119462657949;6118.357572454917;4209.064524669444;4484.529124441952;4536.070105628338;4544.636609017281;4629.421322334708;3825.598312177237;4066.4916885389325;4336.783160841946;4601.654875884322;4676.415448068991;4059.8845454727257;4949.430815456197;5011.696958790668;5023.2214006829;5066.544011039025;4425.712328698624;5032.471590482946;5529.4470552944385;5547.916781248285;5685.914711279334;1501.3544176004423;1581.9005790975161;1582.8394241859778;1586.4050980882168;1586.539903428016;972.557486343712;1098.6703988803326;1099.2470157941837;1130.2145009218514;1198.8141838015122;4886.804527818918;5021.949780502221;5169.079470099382;5206.505381988705;5223.179483079095;5261.76322065508;5392.568594512508;5474.576610081407;5805.187273781674;5820.447057529528;4699.295105734099;4718.60801063687;5205.947940520547;5224.686518808883;5307.946920530822;2706.458708258356;2786.8049236553247;3092.9431927768233;3103.596532450812;3124.5313203019728;5258.526732594085;5818.8254352369495;5979.970100149559;6210.757848430241;6210.9068363974575;5304.92042619361;5459.754311056012;5560.998040705343;5788.507976821933;6060.060780550181;4748.6675932685075;5817.88364232713;5887.558433117448;5914.338547653573;6086.878262434727;5225.372955245395;5323.840284791462;5335.493128152819;5506.834794956158;5606.887862242814;5489.423610520837;5612.635178713371;5696.715164241854;5849.678267695318;5918.0766250967745;5132.769025393792;5150.459201783909;5677.687727175045;6043.365446628217;6140.907886381798;4113.382992551141;4358.49877264106;4404.933925991177;4407.779611019372;4435.534268901719;4547.931781023221;5241.449647786927;5586.19036202345;5721.713914304351;5768.278965129359;5297.682139071668;5341.759620816995;5370.543503802221;5586.301712285965;5624.3250809903075;4908.122583159264;5234.685918844882;5295.894082118413;5571;5633.746481408264;4381.627561657295;5383.811566595307;5453.918191227214;5506.421825708243;5550.27846379968;4661.394018777537;5090.389153301628;5637.943620563742;5713.9428605715075;5854.677992710442;4191.203671302443;5500.614956952975;5542.2795036644975;5596;5597.020521408818 02:48:18 INFO - PROCESS | 1638 | |5;yui.html;699.1889408286389;700.425650966208;736.7495051682429;754.3135746470709;766.3677466090477;15509.922450387747;15892.20538973051;16111.194440277968;16373.918130409362;16398.754018689713;14733.189674567895;15026.248687565605;15092.320845561948;15219.391224351013;15337.463188788395;928.0626567167176;928.73995281321;936.8220038192756;945.3666043750703;948.2319321349694;925.7963248085445;928.8513837785922;930.0838673906197;943.1087622197026;943.7546237978106;1001.8797744270661;1008;1009.4195837393492;1010.5553556435145;1011.5751384418525;991.77189246473;998.2862253488718;1004.1163775877181;1005.8591797148405;1007.1539906478556;745.7949064007387;747.7906186267853;748.6481353763722;748.7117459778025;750.2010358967682;16545.91727041357;16974.745378819327;17039;17158.742618860728;17243.758311419435;14041.087329323562;14082.859171408258;14093.436262549485;14096.872250219958;14227.063008838946;10600.204984626183;11800.940995295054;11818.763624727544;11985.520579176822;12043.397830108459;3236.1262459136137;3542.8051457170104;3750.231202603487;3759.191773768653;3820.7134464914966;107962.46018769855;117624;119060;120941;121695;10401.63594274212;10539.84190237147;10683.62607308756;11095.167862410352;11188.832167517494;22442.663360049613;22930.65604015941;23015.309540713806;23080;23080.4229894254;7659.195784442665;7675.385969122457;7725.5364678119495;7763.805904852424;7821.921780782233;4293.184294983943;4358.36803663467;4361.847335343247;4387.539308372575;4388.05656783793;3012.472817256971;3440.4323286657423;3531.5408996830247;3534.6642069003406;3867.961320386816;670.3966430212847;671.6675245753286;678.6674529480617;686.7699320727512;690.1545606631936;561.3852831149884;685.1983179679787;693.8716337477583;694.4791406445166;710.3784188834769;9707.417554946725;10312.639057632947;10456.424896630759;10479.790404191874;10589;654.8853950558633;687.9896801547981;696.571608460793;696.6516741629185;707.2927072927073;4782.689125206851;5069.239614057921;5075.97462012695;5079.4412614612365;5082.085224659523;98826.5058674702;110022.44988775004;110568.52984307779;111019;111616;75260.24739752641;76420.47159056787;87054.25891482134;87890;99730.5013474928;78799;80365.79451308351;81824.18175818166;82221.17778822254;83146.58426707948;9520.000399958182;9815.4808988651;10705;11064.391458469861;11289.322640641587;45804.02273852189;48369.7907552314;50146.99706005932;50908.23637645513;52293.69265768234;43899.561004390824;53035;55657.60855978633;67662;74224.62887685743;12811.615651530468;12984.870151298745;13134.146280491737;13135.277559734306;13223.338833058117;14780.63048423755;14991.17548534841;15347.309371078552;15377.154256516;15677.686446270782;13561.254131022888;14154.929225353808;14307.427702892184;14391.640208994859;14399;13531.188128712309;14070.507532236732;14248.71502569922;14721.631959201106;14802.481913133419;13418.329083545588;13480.393382297623;13733;14156.150630962175;14411.855881441052;9062.50156241413;9284.907150928675;9408.29437792182;9436.009219032177;9581.233501319739;9584.185344245818;10225.43760093202;10531.736706582396;10928.617498387519;11218.382988935688 02:48:18 INFO - PROCESS | 1638 | __end_tp_report 02:48:18 INFO - PROCESS | 1638 | __start_cc_report 02:48:18 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,411 02:48:18 INFO - PROCESS | 1638 | __end_cc_report 02:48:18 INFO - PROCESS | 1638 | __startTimestamp1492076898064__endTimestamp 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | ------- Summary: start ------- 02:48:18 INFO - PROCESS | 1638 | Number of tests: 6 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5994.27 Median:6247.66 stddev:715.03 (11.4%) stddev-sans-first:669.27 02:48:18 INFO - PROCESS | 1638 | Values: 2712.4 3133.2 3489.0 3491.1 3495.9 6035.9 6076.6 6086.3 6130.5 6189.8 6096.4 6115.3 6162.4 6163.3 6192.8 5443.2 5471.2 5518.8 5589.4 5616.9 5577.7 5582.0 5582.7 5588.8 5591.7 5579.5 5584.8 5592.2 5597.1 5610.7 5347.1 5356.3 5402.1 5578.5 5587.8 4461.4 5453.6 5464.1 5469.2 5505.9 6195.8 6250.6 6261.3 6263.9 6266.9 6301.5 6354.1 6387.6 6430.8 6458.8 6258.1 6302.8 6305.7 6312.7 6374.5 6179.3 6244.3 6248.1 6271.6 6304.2 6655.1 6686.2 6722.6 6762.1 6765.6 6216.3 6247.7 6287.8 6354.7 6356.9 6391.9 6423.5 6453.0 6466.4 6468.8 6350.7 6400.7 6451.5 6473.6 6511.5 6212.3 6329.1 6400.9 6407.6 6430.0 5139.8 5150.4 5182.6 5187.0 5207.1 3741.8 4537.0 4599.5 4639.4 4672.6 6203.0 6250.1 6258.1 6272.8 6302.9 6560.7 6564.9 6612.2 6617.8 6649.2 6015.9 6101.8 6108.8 6122.0 6135.7 5743.9 5767.7 5770.1 5807.7 5809.4 6769.2 6787.3 6798.2 6811.6 6817.1 5539.5 5564.8 6244.7 6330.4 6485.5 5286.7 6683.1 6692.4 6722.2 6748.3 6393.8 6431.9 6456.9 6465.4 6466.0 6218.4 6337.6 6490.3 6571.2 6584.1 6228.6 6238.4 6244.8 6346.6 6389.1 6323.2 6337.8 6343.7 6345.6 6364.3 6148.9 6185.9 6249.3 6276.5 6296.5 4911.4 5576.3 6163.9 6202.7 6270.5 6218.9 6242.6 6253.0 6258.0 6288.1 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:58447.59 Median:28401.15 stddev:123771.92 (435.8%) stddev-sans-first:124042.87 02:48:18 INFO - PROCESS | 1638 | Values: 576.8 793.2 814.4 945.5 949.8 37227.8 38340.4 39890.0 39906.0 39942.0 30009.8 38335.0 38714.6 38774.4 38782.0 7789.1 7913.6 7924.9 10468.8 10751.8 10504.3 10662.7 10669.4 10671.9 10685.7 10657.1 10688.4 10708.1 10745.9 10765.4 10638.3 10644.8 10675.9 10716.4 10716.9 10380.0 10435.8 10451.6 10457.3 10467.3 33930.3 34037.5 34052.3 34067.5 34104.5 3696.7 3721.8 3755.2 3766.8 3811.3 24737.0 24770.3 24994.9 25504.0 26238.0 2701.8 2728.8 3009.5 3134.0 3239.8 324050.0 329349.0 368586.0 368690.0 423184.0 51420.5 51665.7 54650.5 54738.6 54833.7 41452.8 56909.1 57081.7 57516.7 57683.0 15068.2 15084.5 15091.9 15099.0 15104.5 12908.4 12918.6 12953.9 12961.4 12991.5 11915.4 12617.5 13533.4 13541.2 13615.3 6082.3 6096.4 6104.1 6117.5 6138.0 1091.2 1093.4 1103.1 1104.4 1151.3 25938.2 26401.0 26524.5 26699.3 27419.6 1010.6 1193.7 1213.1 1218.2 1246.4 11075.7 13580.2 13943.2 13956.7 13981.6 489240.0 498439.0 500311.0 501388.0 501574.0 56685.0 56911.7 57616.4 57949.4 57997.0 1401.1 1451.3 1454.5 1464.6 1469.7 21374.7 25440.5 25818.8 26243.6 26365.0 44861.0 45400.0 47412.3 49105.0 49257.3 512023.0 513831.0 513928.0 517191.0 518674.0 26101.5 26276.5 30734.4 31311.5 32290.2 38149.4 38232.0 38537.4 38910.0 39331.4 27012.7 27864.9 27912.4 27922.7 28304.9 25842.0 27702.4 28479.7 28530.7 28561.0 31738.7 32672.0 32781.0 32874.2 32891.0 27782.7 28295.2 28322.6 28694.6 29203.6 18404.7 20252.4 20405.9 22730.5 23335.5 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43026.67 Median:26585.67 stddev:130257.57 (490.0%) stddev-sans-first:130583.57 02:48:18 INFO - PROCESS | 1638 | Values: 356.0 383.2 403.1 404.0 410.3 15533.5 19819.4 20815.6 21190.3 21207.7 19088.9 19282.8 20788.9 21190.0 21501.9 5992.3 6305.0 6394.2 6397.0 6411.2 6288.4 6382.2 6406.9 6408.6 6430.2 6423.9 6431.4 6434.6 6440.5 6450.5 6212.9 6377.0 6409.6 6505.8 6520.2 6325.9 6329.3 6338.5 6339.2 6802.7 25677.9 26040.6 26142.9 26446.0 26725.3 3207.5 3272.5 3275.8 3279.5 3295.3 14951.3 16501.3 16529.8 16644.8 16910.5 2013.3 2039.3 2042.3 2043.4 2044.3 94820.0 104819.0 108223.0 151146.2 158629.0 20253.8 20526.4 21183.0 21202.9 21268.9 31692.9 32026.7 32555.5 32636.8 32762.0 10324.2 10467.7 10720.4 10732.7 10806.5 7351.4 8867.1 8869.1 8875.5 9018.8 9627.3 9834.5 9843.8 9895.9 9930.9 3314.6 3390.8 4102.8 4118.6 4125.6 791.5 847.3 887.1 891.7 913.5 21528.0 22148.3 22158.6 22233.6 22249.8 895.4 907.7 913.4 917.1 919.2 9380.7 11143.9 11447.8 11524.8 11548.1 744384.0 781529.0 795065.0 796267.0 807183.0 154796.2 155267.0 155567.2 160669.0 161435.0 1172.3 1176.5 1193.8 1195.0 1203.2 14918.5 18022.6 18847.2 19226.7 19517.8 35558.0 54658.0 58459.5 62228.0 64422.4 80739.6 84665.6 87499.0 95510.5 98945.0 7606.6 9660.4 10912.7 10919.5 11175.6 12244.5 13087.9 13135.8 13171.9 13573.7 8413.8 11216.7 11516.4 11766.5 11786.5 9306.3 10535.8 10587.5 10626.0 10667.9 8007.9 11385.4 11598.1 11599.0 11984.9 9674.4 11517.2 11693.8 11809.0 11816.4 11133.3 11286.6 11409.0 11547.0 11676.1 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6660.11 Median:3084.13 stddev:4201.45 (136.2%) stddev-sans-first:4194.32 02:48:18 INFO - PROCESS | 1638 | Values: 1354.5 1454.8 1531.4 1546.6 1554.3 10634.3 11147.2 11326.5 11345.2 11442.1 9065.4 10718.6 10892.8 10896.4 10896.8 2358.8 2385.2 2391.1 2394.2 2413.7 2338.4 2413.0 2422.0 2427.3 2438.4 2353.5 2421.5 2425.7 2437.5 2439.4 2352.4 2383.2 2392.0 2393.8 2422.8 2377.2 2392.3 2406.4 2417.9 2420.6 8890.3 9950.2 11027.9 11146.3 11170.2 5766.3 5828.8 5921.2 5935.5 5954.6 2977.0 3231.7 3361.1 3527.6 3585.2 1124.3 1136.9 1148.1 1155.2 1167.8 11111.1 13650.0 14711.6 15539.8 15593.7 3921.8 4060.4 4557.8 4754.7 4757.5 9770.7 11750.4 11822.5 11911.6 11925.6 5682.5 5770.6 5778.6 5792.3 5806.8 5173.4 5243.9 5246.1 5264.4 5281.5 3191.3 3249.6 3859.2 3967.1 3968.7 1024.8 1026.6 1028.0 1037.4 1049.2 785.3 796.8 803.8 805.6 811.8 6487.6 6494.4 6621.5 6635.2 6703.4 748.0 778.3 947.2 953.2 956.5 4038.8 4057.0 4059.0 4173.6 4182.1 13872.2 14006.5 14515.9 14525.1 14579.4 14064.0 14313.3 14416.5 14423.4 14457.2 8788.7 10404.2 10524.1 10611.6 10620.9 6350.2 6756.8 6815.9 6880.3 6929.1 11392.4 12081.6 12089.5 12112.0 12129.2 11991.9 12114.8 12118.4 12192.9 12285.3 8361.2 8362.0 8378.5 8423.6 8451.2 10185.2 10327.1 10371.7 10377.6 10378.3 8609.4 8755.1 8809.2 8837.0 8882.9 8611.8 8625.6 8636.8 8670.8 8815.4 7853.6 8009.7 8016.4 8019.7 8046.4 5711.8 5719.3 5729.3 5937.3 5957.0 5979.1 6036.8 6074.5 6244.0 6300.0 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4628.22 Median:5027.85 stddev:1313.24 (26.1%) stddev-sans-first:1276.40 02:48:18 INFO - PROCESS | 1638 | Values: 314.9 340.2 365.9 372.6 382.0 4846.2 5108.9 5386.9 5424.5 5444.2 4432.9 4483.5 4854.4 5135.7 5233.9 3913.3 4198.3 4337.6 4347.7 4379.8 3596.9 3934.8 4315.7 4374.7 4448.6 3574.4 3754.7 4353.8 4380.8 4439.8 3883.8 3986.9 4244.3 4348.5 4400.5 3497.7 3828.2 3935.4 3983.7 3994.8 4424.6 4840.7 5145.0 5208.8 5215.2 3989.7 4182.8 4428.0 4693.8 4907.4 4816.0 5408.6 5552.8 5559.9 5587.6 4371.8 4431.8 4473.0 4521.9 4525.3 5689.7 5828.6 5831.6 6001.1 6118.4 4209.1 4484.5 4536.1 4544.6 4629.4 3825.6 4066.5 4336.8 4601.7 4676.4 4059.9 4949.4 5011.7 5023.2 5066.5 4425.7 5032.5 5529.4 5547.9 5685.9 1501.4 1581.9 1582.8 1586.4 1586.5 972.6 1098.7 1099.2 1130.2 1198.8 4886.8 5021.9 5169.1 5206.5 5223.2 5261.8 5392.6 5474.6 5805.2 5820.4 4699.3 4718.6 5205.9 5224.7 5307.9 2706.5 2786.8 3092.9 3103.6 3124.5 5258.5 5818.8 5980.0 6210.8 6210.9 5304.9 5459.8 5561.0 5788.5 6060.1 4748.7 5817.9 5887.6 5914.3 6086.9 5225.4 5323.8 5335.5 5506.8 5606.9 5489.4 5612.6 5696.7 5849.7 5918.1 5132.8 5150.5 5677.7 6043.4 6140.9 4113.4 4358.5 4404.9 4407.8 4435.5 4547.9 5241.4 5586.2 5721.7 5768.3 5297.7 5341.8 5370.5 5586.3 5624.3 4908.1 5234.7 5295.9 5571.0 5633.7 4381.6 5383.8 5453.9 5506.4 5550.3 4661.4 5090.4 5637.9 5713.9 5854.7 4191.2 5500.6 5542.3 5596.0 5597.0 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20866.77 Median:3485.99 stddev:30462.07 (873.8%) stddev-sans-first:30509.88 02:48:18 INFO - PROCESS | 1638 | Values: 699.2 700.4 736.7 754.3 766.4 15509.9 15892.2 16111.2 16373.9 16398.8 14733.2 15026.2 15092.3 15219.4 15337.5 928.1 928.7 936.8 945.4 948.2 925.8 928.9 930.1 943.1 943.8 1001.9 1008.0 1009.4 1010.6 1011.6 991.8 998.3 1004.1 1005.9 1007.2 745.8 747.8 748.6 748.7 750.2 16545.9 16974.7 17039.0 17158.7 17243.8 14041.1 14082.9 14093.4 14096.9 14227.1 10600.2 11800.9 11818.8 11985.5 12043.4 3236.1 3542.8 3750.2 3759.2 3820.7 107962.5 117624.0 119060.0 120941.0 121695.0 10401.6 10539.8 10683.6 11095.2 11188.8 22442.7 22930.7 23015.3 23080.0 23080.4 7659.2 7675.4 7725.5 7763.8 7821.9 4293.2 4358.4 4361.8 4387.5 4388.1 3012.5 3440.4 3531.5 3534.7 3868.0 670.4 671.7 678.7 686.8 690.2 561.4 685.2 693.9 694.5 710.4 9707.4 10312.6 10456.4 10479.8 10589.0 654.9 688.0 696.6 696.7 707.3 4782.7 5069.2 5076.0 5079.4 5082.1 98826.5 110022.4 110568.5 111019.0 111616.0 75260.2 76420.5 87054.3 87890.0 99730.5 78799.0 80365.8 81824.2 82221.2 83146.6 9520.0 9815.5 10705.0 11064.4 11289.3 45804.0 48369.8 50147.0 50908.2 52293.7 43899.6 53035.0 55657.6 67662.0 74224.6 12811.6 12984.9 13134.1 13135.3 13223.3 14780.6 14991.2 15347.3 15377.2 15677.7 13561.3 14154.9 14307.4 14391.6 14399.0 13531.2 14070.5 14248.7 14721.6 14802.5 13418.3 13480.4 13733.0 14156.2 14411.9 9062.5 9284.9 9408.3 9436.0 9581.2 9584.2 10225.4 10531.7 10928.6 11218.4 02:48:18 INFO - PROCESS | 1638 | -------- Summary: end -------- 02:48:18 INFO - PROCESS | 1638 | 02:48:18 INFO - TEST-INFO | 1638: exit 0 02:48:18 INFO - TEST-OK | dromaeo_css | took 1117251ms 02:48:18 INFO - TEST-START | kraken 02:48:18 INFO - operating with platform_type : mac_ 02:48:18 INFO - Initialising browser for kraken test... 02:48:18 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpZtjw1T/profile http://localhost:49236/getInfo.html) 02:48:21 INFO - PROCESS | 1650 | 2017-04-13 02:48:21.638 plugin-container[1652:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 02:48:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:21 INFO - PROCESS | 1650 | 2017-04-13 02:48:21.639 plugin-container[1652:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 02:48:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:21 INFO - PROCESS | 1650 | 2017-04-13 02:48:21.639 plugin-container[1652:10233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:48:21 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:48:21 INFO - PROCESS | 1650 | colorDepth:24 02:48:21 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:48:21 INFO - PROCESS | 1650 | __metrics 02:48:21 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:48:22 INFO - TEST-INFO | 1650: exit 0 02:48:22 INFO - Browser initialized. 02:48:22 INFO - Running cycle 1/1 for kraken test... 02:48:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpZtjw1T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:24 INFO - PROCESS | 1653 | 2017-04-13 02:48:24.974 plugin-container[1654:10359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 02:48:24 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:24 INFO - PROCESS | 1653 | 2017-04-13 02:48:24.975 plugin-container[1654:10359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 02:48:24 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:48:24 INFO - PROCESS | 1653 | 2017-04-13 02:48:24.975 plugin-container[1654:10359] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:48:34 INFO - PROCESS | 1653 | RSS: Main: 220676096 02:48:34 INFO - PROCESS | 1653 | 02:48:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:38 INFO - PROCESS | 1653 | RSS: Main: 235343872 02:48:38 INFO - PROCESS | 1653 | 02:48:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 02:48:42 INFO - PROCESS | 1653 | RSS: Main: 243277824 02:48:42 INFO - PROCESS | 1653 | 02:48:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 02:48:45 INFO - PROCESS | 1653 | RSS: Main: 246939648 02:48:45 INFO - PROCESS | 1653 | 02:48:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 02:48:48 INFO - PROCESS | 1653 | RSS: Main: 247472128 02:48:48 INFO - PROCESS | 1653 | 02:48:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:51 INFO - PROCESS | 1653 | RSS: Main: 248819712 02:48:51 INFO - PROCESS | 1653 | 02:48:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:54 INFO - PROCESS | 1653 | RSS: Main: 252665856 02:48:54 INFO - PROCESS | 1653 | 02:48:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:58 INFO - PROCESS | 1653 | RSS: Main: 252092416 02:48:58 INFO - PROCESS | 1653 | 02:49:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 02:49:02 INFO - PROCESS | 1653 | RSS: Main: 252260352 02:49:02 INFO - PROCESS | 1653 | 02:49:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:05 INFO - PROCESS | 1653 | RSS: Main: 251641856 02:49:05 INFO - PROCESS | 1653 | 02:49:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:07 INFO - PROCESS | 1653 | RSS: Main: 251691008 02:49:07 INFO - PROCESS | 1653 | 02:49:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:10 INFO - PROCESS | 1653 | RSS: Main: 251826176 02:49:10 INFO - PROCESS | 1653 | 02:49:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:13 INFO - PROCESS | 1653 | RSS: Main: 252162048 02:49:13 INFO - PROCESS | 1653 | 02:49:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:17 INFO - PROCESS | 1653 | RSS: Main: 251813888 02:49:17 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 02:49:19 INFO - PROCESS | 1653 | RSS: Main: 253943808 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | __start_tp_report 02:49:19 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:49:19 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:49:19 INFO - PROCESS | 1653 | |i|pagename|runs| 02:49:19 INFO - PROCESS | 1653 | |0;ai-astar;108;121;119;120;117;128;178;121;119;120 02:49:19 INFO - PROCESS | 1653 | |1;audio-beat-detection;144;115;113;147;115;130;145;109;121;133 02:49:19 INFO - PROCESS | 1653 | |2;audio-dft;201;198;199;198;200;201;202;200;204;195 02:49:19 INFO - PROCESS | 1653 | |3;audio-fft;87;64;82;92;64;79;85;62;72;62 02:49:19 INFO - PROCESS | 1653 | |4;audio-oscillator;85;70;86;72;71;70;83;70;69;84 02:49:19 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;90;90;90;94;89;96;111;94;99 02:49:19 INFO - PROCESS | 1653 | |6;imaging-darkroom;98;126;106;157;153;155;114;97;97;98 02:49:19 INFO - PROCESS | 1653 | |7;imaging-desaturate;89;94;132;90;82;140;146;85;144;77 02:49:19 INFO - PROCESS | 1653 | |8;json-parse-financial;60;66;63;66;92;94;94;60;95;97 02:49:19 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;55;63;58;63;78;88;65;59;60;65 02:49:19 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;89;74;94;73;76;79;74;76;78;74 02:49:19 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;139;152;136;139;137;141;142;145;182;179 02:49:19 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;172;174;172;170;168;166;163;184;168;162 02:49:19 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;67;67;68;66;69;76;84;85 02:49:19 INFO - PROCESS | 1653 | __end_tp_report 02:49:19 INFO - PROCESS | 1653 | __start_cc_report 02:49:19 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1016 02:49:19 INFO - PROCESS | 1653 | __end_cc_report 02:49:19 INFO - PROCESS | 1653 | __startTimestamp1492076959395__endTimestamp 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:49:19 INFO - PROCESS | 1653 | Number of tests: 14 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:125.10 Median:120.50 stddev:19.22 (16.0%) stddev-sans-first:19.36 02:49:19 INFO - PROCESS | 1653 | Values: 108.0 121.0 119.0 120.0 117.0 128.0 178.0 121.0 119.0 120.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:131.50 stddev:14.54 (11.1%) stddev-sans-first:14.09 02:49:19 INFO - PROCESS | 1653 | Values: 144.0 115.0 113.0 147.0 115.0 130.0 145.0 109.0 121.0 133.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:199.80 Median:200.50 stddev:2.49 (1.2%) stddev-sans-first:2.60 02:49:19 INFO - PROCESS | 1653 | Values: 201.0 198.0 199.0 198.0 200.0 201.0 202.0 200.0 204.0 195.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:74.90 Median:80.50 stddev:11.48 (14.3%) stddev-sans-first:11.31 02:49:19 INFO - PROCESS | 1653 | Values: 87.0 64.0 82.0 92.0 64.0 79.0 85.0 62.0 72.0 62.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.50 stddev:7.39 (9.5%) stddev-sans-first:7.09 02:49:19 INFO - PROCESS | 1653 | Values: 85.0 70.0 86.0 72.0 71.0 70.0 83.0 70.0 69.0 84.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:92.00 stddev:6.72 (7.3%) stddev-sans-first:6.94 02:49:19 INFO - PROCESS | 1653 | Values: 90.0 90.0 90.0 90.0 94.0 89.0 96.0 111.0 94.0 99.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:120.10 Median:127.00 stddev:25.77 (20.3%) stddev-sans-first:26.06 02:49:19 INFO - PROCESS | 1653 | Values: 98.0 126.0 106.0 157.0 153.0 155.0 114.0 97.0 97.0 98.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:83.50 stddev:28.65 (34.3%) stddev-sans-first:29.56 02:49:19 INFO - PROCESS | 1653 | Values: 89.0 94.0 132.0 90.0 82.0 140.0 146.0 85.0 144.0 77.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:78.70 Median:93.00 stddev:16.71 (18.0%) stddev-sans-first:16.30 02:49:19 INFO - PROCESS | 1653 | Values: 60.0 66.0 63.0 66.0 92.0 94.0 94.0 60.0 95.0 97.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:64.00 stddev:10.08 (15.7%) stddev-sans-first:9.96 02:49:19 INFO - PROCESS | 1653 | Values: 55.0 63.0 58.0 63.0 78.0 88.0 65.0 59.0 60.0 65.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:77.00 stddev:7.10 (9.2%) stddev-sans-first:6.48 02:49:19 INFO - PROCESS | 1653 | Values: 89.0 74.0 94.0 73.0 76.0 79.0 74.0 76.0 78.0 74.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:143.50 stddev:17.13 (11.9%) stddev-sans-first:17.76 02:49:19 INFO - PROCESS | 1653 | Values: 139.0 152.0 136.0 139.0 137.0 141.0 142.0 145.0 182.0 179.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:171.00 stddev:6.30 (3.7%) stddev-sans-first:6.63 02:49:19 INFO - PROCESS | 1653 | Values: 172.0 174.0 172.0 170.0 168.0 166.0 163.0 184.0 168.0 162.0 02:49:19 INFO - PROCESS | 1653 | 02:49:19 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:68.50 stddev:7.44 (10.9%) stddev-sans-first:7.71 02:49:19 INFO - PROCESS | 1653 | Values: 67.0 66.0 67.0 67.0 68.0 66.0 69.0 76.0 84.0 85.0 02:49:19 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:49:19 INFO - PROCESS | 1653 | 02:49:20 INFO - TEST-INFO | 1653: exit 0 02:49:20 INFO - TEST-OK | kraken | took 61606ms 02:49:20 INFO - SUITE-END | took 1178s 02:49:20 INFO - Completed test suite (00:19:39) 02:49:20 INFO - javascript benchmark 02:49:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7671.663549350095, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2712.40327128032, 3133.15404840693, 3489.040513858688, 3491.0748651607328, 3495.947560786589, 6035.879282414349, 6076.574639775217, 6086.30007549132, 6130.540209484284, 6189.783357582485, 6096.420840020202, 6115.266168059839, 6162.414570615795, 6163.322034576193, 6192.81421557354, 5443.2107344435035, 5471.179323101546, 5518.806841760539, 5589.413111623282, 5616.859578510529, 5577.69322687254, 5581.972090139564, 5582.665040097587, 5588.776448942058, 5591.692456914868, 5579.46995035473, 5584.776608935637, 5592.217089607459, 5597.104463285875, 5610.747516361753, 5347.0909945309395, 5356.250124982505, 5402.108652072403, 5578.470045345685, 5587.804426845061, 4461.42001539801, 5453.563714902838, 5464.1257398816, 5469.234307196995, 5505.944940550583, 6195.783147589857, 6250.593711408789, 6261.311255761908, 6263.906041409383, 6266.937330626681, 6301.464375528086, 6354.078658594524, 6387.552871299011, 6430.839229019265, 6458.838529036811, 6258.092576576371, 6302.842428939267, 6305.716242769086, 6312.652804095819, 6374.458171055466, 6179.3202747697715, 6244.344343843876, 6248.062790581449, 6271.623702577815, 6304.211973503312, 6655.101561289261, 6686.1642294713165, 6722.630255336004, 6762.053312536151, 6765.560238584476, 6216.285127210338, 6247.656378899204, 6287.842803929847, 6354.650494222862, 6356.904646430307, 6391.872162556723, 6423.518236132309, 6452.9677351612945, 6466.385693359123, 6468.8059358219325, 6350.682465876687, 6400.743970241231, 6451.451626611697, 6473.579217350857, 6511.479081673455, 6212.316645169028, 6329.145565348619, 6400.871982560323, 6407.583507072025, 6429.9678501607195, 5139.7687104080405, 5150.40770311419, 5182.6372153949615, 5187.0, 5207.062728708793, 3741.8316175772156, 4537.001859590885, 4599.5400459953735, 4639.420072490939, 4672.6261899047995, 6203.0, 6250.062490626351, 6258.123862659231, 6272.780452684134, 6302.936970630327, 6560.704768285439, 6564.934350656527, 6612.206535215709, 6617.768378106839, 6649.202095748541, 6015.879682406415, 6101.786437474667, 6108.7670647404, 6121.96939015311, 6135.692037377249, 5743.85640358986, 5767.653940763567, 5770.105633626795, 5807.738651760681, 5809.448102430262, 6769.187697476433, 6787.287334829772, 6798.150231221098, 6811.591304521547, 6817.079694241214, 5539.529140023144, 5564.831385409109, 6244.68776561161, 6330.3669633036325, 6485.546011779319, 5286.709230992333, 6683.097781799394, 6692.431143352869, 6722.159730033746, 6748.291429399842, 6393.808185754435, 6431.903521447276, 6456.903146452713, 6465.353464653497, 6465.967670161619, 6218.440340369208, 6337.588056776295, 6490.253620833664, 6571.211454625475, 6584.078229048033, 6228.564000520101, 6238.438540531374, 6244.843878903063, 6346.555741098079, 6389.073584330339, 6323.20959880015, 6337.809865703852, 6343.651099189589, 6345.587536810092, 6364.299927007933, 6148.877022459436, 6185.876282474415, 6249.281332646804, 6276.529260305404, 6296.496280297473, 4911.410630724193, 5576.275084239023, 6163.907541386793, 6202.689865506797, 6270.529710271657, 6218.875622487615, 6242.5942313749465, 6252.968735156477, 6258.0, 6288.0882272069375], "value": 5945.099091607764, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.7779404929104, 793.2067932067931, 814.3566582399905, 945.5461378538306, 949.7957939043105, 37227.81386093069, 38340.424893626616, 39890.0, 39906.0, 39941.99999999996, 30009.849950750217, 38335.04162395941, 38714.612853871455, 38774.418383724194, 38782.03044923878, 7789.104253010905, 7913.564753938531, 7924.881126783102, 10468.842967355497, 10751.784964300708, 10504.2647014709, 10662.733431664232, 10669.359838409682, 10671.946640266826, 10685.732856678567, 10657.147428205724, 10688.358698478116, 10708.143348532138, 10745.946270268638, 10765.407902565355, 10638.255322127454, 10644.787104257948, 10675.946620266926, 10716.41059741714, 10716.946415267912, 10380.0, 10435.843462348072, 10451.581936722521, 10457.267991240617, 10467.267291289612, 33930.32139357224, 34037.489437658456, 34052.31895362079, 34067.488987664954, 34104.48843267353, 3696.722745794076, 3721.8325175366904, 3755.2489502099415, 3766.8493260269556, 3811.27585758705, 24737.010519579195, 24770.25689229326, 24994.875025624937, 25504.0, 26238.0, 2701.7977000234705, 2728.8089833711647, 3009.50343193375, 3133.984330078335, 3239.7732158748895, 324050.0, 329349.0, 368586.0, 368690.0, 423184.0, 51420.48579514232, 51665.741671291406, 54650.453495464535, 54738.63153421196, 54833.72583137138, 41452.79273603613, 56909.14636280459, 57081.714591427604, 57516.71241643765, 57683.0, 15068.246587670572, 15084.472043478645, 15091.943563950637, 15099.0, 15104.471343503144, 12908.419121139572, 12918.612441626767, 12953.870461295455, 12961.41673624689, 12991.480340786451, 11915.404229788475, 12617.495300188073, 13533.39099740514, 13541.187528748307, 13615.31923403826, 6082.300535438393, 6096.3903609639565, 6104.053871649901, 6117.541184411098, 6138.0, 1091.24158709696, 1093.4150229627112, 1103.1119948441421, 1104.4091411095026, 1151.2631915574038, 25938.22185334443, 26401.0, 26524.46951060968, 26699.33251668724, 27419.588706169423, 1010.5654568535454, 1193.731410432663, 1213.120487646467, 1218.2386008744534, 1246.4266437438698, 11075.72310692239, 13580.185188888698, 13943.16341019542, 13956.651083722989, 13981.580552583438, 489240.0, 498439.0, 500311.0, 501388.0, 501574.0, 56685.0, 56911.71544142253, 57616.4238357611, 57949.420505794405, 57997.0, 1401.0962928910797, 1451.2888684544498, 1454.461849115834, 1464.6191990082655, 1469.7354476194173, 21374.679379809626, 25440.491190175722, 25818.838152282817, 26243.60634590521, 26365.0, 44861.0, 45400.0, 47412.2888156671, 49105.0, 49257.2611410822, 512023.0, 513831.0, 513928.0, 517191.0, 518674.0, 26101.477970440865, 26276.474470510864, 30734.385312294075, 31311.530327044657, 32290.19274518062, 38149.4277585842, 38232.0, 38537.421938671505, 38910.0, 39331.410028850165, 27012.72987270102, 27864.86067569649, 27912.44175116527, 27922.743476544012, 28304.85847570749, 25842.0, 27702.445951080463, 28479.715202847707, 28530.714692852805, 28561.0, 31738.682613173572, 32672.0, 32781.0, 32874.17814554655, 32891.0, 27782.722172778012, 28295.15114546567, 28322.575161372184, 28694.56958145671, 29203.561946571244, 18404.723929140804, 20252.392428227176, 20405.897970510054, 22730.545389092455, 23335.53328933378], "value": 18504.20367763177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.96796288334053, 383.243094887597, 403.0709215258829, 403.9698768141241, 410.2785575469662, 15533.53399398017, 19819.405417837454, 20815.583688326224, 21190.258340958088, 21207.68188477174, 19088.904555477202, 19282.843029418244, 20788.896055519737, 21190.0, 21501.892490537524, 5992.25096862891, 6305.0, 6394.232692076957, 6396.968015159918, 6411.198600174978, 6288.402601752826, 6382.20222472191, 6406.871862562769, 6408.58344207625, 6430.196225471816, 6423.871522569546, 6431.42117209451, 6434.646094464802, 6440.484761219114, 6450.548461607689, 6212.875742485147, 6376.9681151594405, 6409.615423074607, 6505.837354066139, 6520.152380190592, 6325.936740632581, 6329.2721337045905, 6338.492920566389, 6339.175907132089, 6802.727890884359, 25677.871610642014, 26040.609390859154, 26142.86928565364, 26446.0, 26725.331866703487, 3207.5188721691698, 3272.5091236314506, 3275.8034517929, 3279.4916787897914, 3295.275039491332, 14951.327190276355, 16501.33994640213, 16529.752053719203, 16644.750328745078, 16910.492685219462, 2013.2752209204675, 2039.2964427272568, 2042.2954080842085, 2043.4482689673705, 2044.2742826296599, 94820.0, 104819.0, 108223.0, 151146.24426877795, 158629.0, 20253.79746202519, 20526.38420847377, 21183.0, 21202.893985529972, 21268.893655531625, 31692.89074882368, 32026.67973320237, 32555.51166732501, 32636.83681581577, 32762.017139485855, 10324.174066074696, 10467.685969420929, 10720.35677859331, 10732.731681707864, 10806.51370688321, 7351.41188704913, 8867.113288671082, 8869.068747781519, 8875.467471951575, 9018.819623607491, 9627.277954153467, 9834.459104749165, 9843.75390615226, 9895.851562226573, 9930.851037234448, 3314.6022477302877, 3390.8135052572347, 4102.830693252408, 4118.649914757219, 4125.566815484331, 791.5132193700832, 847.3094428041127, 887.0597167002998, 891.733738091911, 913.5340976102103, 21528.0, 22148.335549933527, 22158.556828863333, 22233.555328893333, 22249.777502225093, 895.3777124898133, 907.650554536495, 913.397157875812, 917.1287277086794, 919.2232563483846, 9380.718578442658, 11143.888561114447, 11447.771044579229, 11524.769504609862, 11548.076153907667, 744384.0, 781529.0, 795065.0, 796267.0, 807183.0, 154796.22601887144, 155267.0, 155567.22216388845, 160669.0, 161435.0, 1172.302480024384, 1176.5352685689104, 1193.761247750436, 1194.9940250298694, 1203.2419575667273, 14918.477853275082, 18022.639547209244, 18847.15187816524, 19226.711599325743, 19517.8048219516, 35558.0, 54658.0, 58459.538511537554, 62228.0, 64422.35577644163, 80739.59630201811, 84665.57667211625, 87499.0, 95510.52244738732, 98945.0, 7606.619669016416, 9660.37207581505, 10912.727181820517, 10919.508622112184, 11175.552977880794, 12244.510219591117, 13087.869121308666, 13135.802962955373, 13171.868281317064, 13573.728525429633, 8413.789655258668, 11216.66350009501, 11516.366599837089, 11766.52933882635, 11786.528538858693, 9306.32486152499, 10535.841962370723, 10587.523561439908, 10626.0, 10667.893321067, 8007.879881801661, 11385.373804440835, 11598.07215422781, 11599.0, 11984.880151198377, 9674.371165874196, 11517.193796434169, 11693.824592631288, 11809.0, 11816.40917954116, 11133.276337038067, 11286.604968826052, 11409.0, 11547.018503427304, 11676.065914726632], "value": 11259.438394559606, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.5123755448037, 1454.7963285140077, 1531.402752926359, 1546.5515000649812, 1554.2617256803017, 10634.255602107856, 11147.219694621379, 11326.546938122465, 11345.205835591512, 11442.14183936206, 9065.365424420293, 10718.571257149724, 10892.782144357108, 10896.40069796161, 10896.836547451774, 2358.841659881207, 2385.1651921827397, 2391.0674836813655, 2394.221877889684, 2413.650020746991, 2338.4037070546988, 2413.046846495633, 2421.987890060547, 2427.2960841356075, 2438.426969662128, 2353.4587044979667, 2421.491486787768, 2425.708914930211, 2437.512497500507, 2439.402346425128, 2352.3766201956323, 2383.2254517281954, 2391.964120538193, 2393.7845593896545, 2422.8304018718695, 2377.191754803358, 2392.294273189413, 2406.3984003999, 2417.9274621761374, 2420.636904464327, 8890.33322500815, 9950.154236889874, 11027.889721102767, 11146.275492092987, 11170.162237832114, 5766.279215098113, 5828.795992140297, 5921.171036054956, 5935.465808077251, 5954.612950158269, 2976.955345669817, 3231.74146068314, 3361.1429085583018, 3527.5590551181103, 3585.229175727205, 1124.269225003746, 1136.9147313951405, 1148.110214583696, 1155.2375432214783, 1167.773837470661, 11111.055560277386, 13650.044496885223, 14711.63220919475, 15539.844601553952, 15593.688126237525, 3921.8431262749173, 4060.3503439449546, 4557.840475583339, 4754.738489383117, 4757.524247575215, 9770.706878793648, 11750.412479375997, 11822.467988940518, 11911.583094591648, 11925.582604608797, 5682.54539636828, 5770.6249093808765, 5778.624389414758, 5792.304923409133, 5806.796762113306, 5173.379194496684, 5243.895122097613, 5246.134387826042, 5264.42091369949, 5281.524662780368, 3191.3138675184946, 3249.6100467943998, 3859.1895701902354, 3967.0677390813134, 3968.722189446711, 1024.7950409918046, 1026.6150193677372, 1027.984580231297, 1037.3620223562605, 1049.197364016528, 785.3010820369875, 796.7729197178892, 803.7548547692893, 805.6414895371504, 811.8011087283535, 6487.61074335532, 6494.415502604788, 6621.503387245976, 6635.2037755469655, 6703.363180497845, 747.9887801682979, 778.3267473635378, 947.2043483473915, 953.1945506047379, 956.5026186383042, 4038.757674539478, 4056.9594304057173, 4058.9594104059174, 4173.603507666767, 4182.079942412725, 13872.167669939836, 14006.509772157926, 14515.92742036283, 14525.128492290496, 14579.416823326948, 14064.0, 14313.28433578317, 14416.495422660366, 14423.42306307757, 14457.204853732936, 8788.736337909744, 10404.167666586654, 10524.105451036748, 10611.628592999208, 10620.893791062144, 6350.206224221973, 6756.7972361733155, 6815.852578581244, 6880.311968803079, 6929.099217101744, 11392.373419462008, 12081.637550873487, 12089.516419343128, 12112.0, 12129.150959432754, 11991.940040299743, 12114.818277726017, 12118.394080295773, 12192.939035304767, 12285.324307163191, 8361.247487726134, 8361.95819020901, 8378.53918034514, 8423.620937057969, 8451.1548845115, 10185.236107291834, 10327.122194613543, 10371.740706482398, 10377.584896604052, 10378.27352085377, 8609.440386374865, 8755.12448755145, 8809.207171354608, 8837.0, 8882.866756998521, 8611.784705382415, 8625.568721564023, 8636.82726345457, 8670.78323041929, 8815.426997245158, 7853.568053757098, 8009.679612815422, 8016.39877009215, 8019.679212831421, 8046.396520261121, 5711.800086997101, 5719.256496655408, 5729.341125770589, 5937.2578427696535, 5957.0, 5979.133025711334, 6036.849078773066, 6074.51403887679, 6243.96878015607, 6300.0234963580715], "value": 5040.0693521624, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.872476646958, 340.1699852360241, 365.9377905756021, 372.61806648185626, 381.99236015279695, 4846.200376937801, 5108.8978220435565, 5386.949544838758, 5424.5117939385445, 5444.2378067070595, 4432.889177770558, 4483.461984561857, 4854.4174699036075, 5135.743212839362, 5233.8953220935555, 3913.2956067907767, 4198.307279298912, 4337.63130133939, 4347.652187824967, 4379.75911324877, 3596.92806143877, 3934.803259837011, 4315.71947823392, 4374.7156434831795, 4448.599626033656, 3574.4280915053596, 3754.6808521275725, 4353.804078816461, 4380.846670366538, 4439.8446054388105, 3883.805809709503, 3986.86045988389, 4244.257254980397, 4348.499922508889, 4400.493943196525, 3497.7376696747847, 3828.2007456495403, 3935.3900145477496, 3983.701222408302, 3994.800259986989, 4424.628365206796, 4840.733759643218, 5145.0, 5208.817691380784, 5215.191645294985, 3989.740666856645, 4182.811773470201, 4427.977860110711, 4693.788779504896, 4907.411110666707, 4815.9927305770025, 5408.59435542332, 5552.806146678484, 5559.8888022239735, 5587.569407307834, 4371.825126994916, 4431.800568974371, 4472.971216620163, 4521.886952826173, 4525.343825145368, 5689.715514224314, 5828.5628577856405, 5831.591788574844, 6001.119462657949, 6118.357572454917, 4209.064524669444, 4484.529124441952, 4536.070105628338, 4544.636609017281, 4629.421322334708, 3825.598312177237, 4066.4916885389325, 4336.783160841946, 4601.654875884322, 4676.415448068991, 4059.8845454727257, 4949.430815456197, 5011.696958790668, 5023.2214006829, 5066.544011039025, 4425.712328698624, 5032.471590482946, 5529.4470552944385, 5547.916781248285, 5685.914711279334, 1501.3544176004423, 1581.9005790975161, 1582.8394241859778, 1586.4050980882168, 1586.539903428016, 972.557486343712, 1098.6703988803326, 1099.2470157941837, 1130.2145009218514, 1198.8141838015122, 4886.804527818918, 5021.949780502221, 5169.079470099382, 5206.505381988705, 5223.179483079095, 5261.76322065508, 5392.568594512508, 5474.576610081407, 5805.187273781674, 5820.447057529528, 4699.295105734099, 4718.60801063687, 5205.947940520547, 5224.686518808883, 5307.946920530822, 2706.458708258356, 2786.8049236553247, 3092.9431927768233, 3103.596532450812, 3124.5313203019728, 5258.526732594085, 5818.8254352369495, 5979.970100149559, 6210.757848430241, 6210.9068363974575, 5304.92042619361, 5459.754311056012, 5560.998040705343, 5788.507976821933, 6060.060780550181, 4748.6675932685075, 5817.88364232713, 5887.558433117448, 5914.338547653573, 6086.878262434727, 5225.372955245395, 5323.840284791462, 5335.493128152819, 5506.834794956158, 5606.887862242814, 5489.423610520837, 5612.635178713371, 5696.715164241854, 5849.678267695318, 5918.0766250967745, 5132.769025393792, 5150.459201783909, 5677.687727175045, 6043.365446628217, 6140.907886381798, 4113.382992551141, 4358.49877264106, 4404.933925991177, 4407.779611019372, 4435.534268901719, 4547.931781023221, 5241.449647786927, 5586.19036202345, 5721.713914304351, 5768.278965129359, 5297.682139071668, 5341.759620816995, 5370.543503802221, 5586.301712285965, 5624.3250809903075, 4908.122583159264, 5234.685918844882, 5295.894082118413, 5571.0, 5633.746481408264, 4381.627561657295, 5383.811566595307, 5453.918191227214, 5506.421825708243, 5550.27846379968, 4661.394018777537, 5090.389153301628, 5637.943620563742, 5713.9428605715075, 5854.677992710442, 4191.203671302443, 5500.614956952975, 5542.2795036644975, 5596.0, 5597.020521408818], "value": 4239.641190026215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.1889408286389, 700.425650966208, 736.7495051682429, 754.3135746470709, 766.3677466090477, 15509.922450387747, 15892.20538973051, 16111.194440277968, 16373.918130409362, 16398.754018689713, 14733.189674567895, 15026.248687565605, 15092.320845561948, 15219.391224351013, 15337.463188788395, 928.0626567167176, 928.73995281321, 936.8220038192756, 945.3666043750703, 948.2319321349694, 925.7963248085445, 928.8513837785922, 930.0838673906197, 943.1087622197026, 943.7546237978106, 1001.8797744270661, 1008.0, 1009.4195837393492, 1010.5553556435145, 1011.5751384418525, 991.77189246473, 998.2862253488718, 1004.1163775877181, 1005.8591797148405, 1007.1539906478556, 745.7949064007387, 747.7906186267853, 748.6481353763722, 748.7117459778025, 750.2010358967682, 16545.91727041357, 16974.745378819327, 17039.0, 17158.742618860728, 17243.758311419435, 14041.087329323562, 14082.859171408258, 14093.436262549485, 14096.872250219958, 14227.063008838946, 10600.204984626183, 11800.940995295054, 11818.763624727544, 11985.520579176822, 12043.397830108459, 3236.1262459136137, 3542.8051457170104, 3750.231202603487, 3759.191773768653, 3820.7134464914966, 107962.46018769855, 117624.0, 119060.0, 120941.0, 121695.0, 10401.63594274212, 10539.84190237147, 10683.62607308756, 11095.167862410352, 11188.832167517494, 22442.663360049613, 22930.65604015941, 23015.309540713806, 23080.0, 23080.4229894254, 7659.195784442665, 7675.385969122457, 7725.5364678119495, 7763.805904852424, 7821.921780782233, 4293.184294983943, 4358.36803663467, 4361.847335343247, 4387.539308372575, 4388.05656783793, 3012.472817256971, 3440.4323286657423, 3531.5408996830247, 3534.6642069003406, 3867.961320386816, 670.3966430212847, 671.6675245753286, 678.6674529480617, 686.7699320727512, 690.1545606631936, 561.3852831149884, 685.1983179679787, 693.8716337477583, 694.4791406445166, 710.3784188834769, 9707.417554946725, 10312.639057632947, 10456.424896630759, 10479.790404191874, 10589.0, 654.8853950558633, 687.9896801547981, 696.571608460793, 696.6516741629185, 707.2927072927073, 4782.689125206851, 5069.239614057921, 5075.97462012695, 5079.4412614612365, 5082.085224659523, 98826.5058674702, 110022.44988775004, 110568.52984307779, 111019.0, 111616.0, 75260.24739752641, 76420.47159056787, 87054.25891482134, 87890.0, 99730.5013474928, 78799.0, 80365.79451308351, 81824.18175818166, 82221.17778822254, 83146.58426707948, 9520.000399958182, 9815.4808988651, 10705.0, 11064.391458469861, 11289.322640641587, 45804.02273852189, 48369.7907552314, 50146.99706005932, 50908.23637645513, 52293.69265768234, 43899.561004390824, 53035.0, 55657.60855978633, 67662.0, 74224.62887685743, 12811.615651530468, 12984.870151298745, 13134.146280491737, 13135.277559734306, 13223.338833058117, 14780.63048423755, 14991.17548534841, 15347.309371078552, 15377.154256516, 15677.686446270782, 13561.254131022888, 14154.929225353808, 14307.427702892184, 14391.640208994859, 14399.0, 13531.188128712309, 14070.507532236732, 14248.71502569922, 14721.631959201106, 14802.481913133419, 13418.329083545588, 13480.393382297623, 13733.0, 14156.150630962175, 14411.855881441052, 9062.50156241413, 9284.907150928675, 9408.29437792182, 9436.009219032177, 9581.233501319739, 9584.185344245818, 10225.43760093202, 10531.736706582396, 10928.617498387519, 11218.382988935688], "value": 7701.672212120703, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 119.0, 120.0, 117.0, 128.0, 178.0, 121.0, 119.0, 120.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 113.0, 147.0, 115.0, 130.0, 145.0, 109.0, 121.0, 133.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 199.0, 198.0, 200.0, 201.0, 202.0, 200.0, 204.0, 195.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 64.0, 82.0, 92.0, 64.0, 79.0, 85.0, 62.0, 72.0, 62.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 86.0, 72.0, 71.0, 70.0, 83.0, 70.0, 69.0, 84.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 94.0, 89.0, 96.0, 111.0, 94.0, 99.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 126.0, 106.0, 157.0, 153.0, 155.0, 114.0, 97.0, 97.0, 98.0], "value": 120.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 132.0, 90.0, 82.0, 140.0, 146.0, 85.0, 144.0, 77.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 66.0, 63.0, 66.0, 92.0, 94.0, 94.0, 60.0, 95.0, 97.0], "value": 78.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 58.0, 63.0, 78.0, 88.0, 65.0, 59.0, 60.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 74.0, 94.0, 73.0, 76.0, 79.0, 74.0, 76.0, 78.0, 74.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 136.0, 139.0, 137.0, 141.0, 142.0, 145.0, 182.0, 179.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 172.0, 170.0, 168.0, 166.0, 163.0, 184.0, 168.0, 162.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 69.0, 76.0, 84.0, 85.0], "value": 71.5, "unit": "score"}]}]} 02:49:20 INFO - Return code: 0 02:49:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:20 ERROR - # TBPL SUCCESS # 02:49:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:20 INFO - 2017-04-13 02:49:20.729 screenresolution[1658:10747] starting screenresolution argv=screenresolution get 02:49:20 INFO - 2017-04-13 02:49:20.732 screenresolution[1658:10747] Display 0: 1600x1200x32@60 02:49:20 INFO - 2017-04-13 02:49:20.738 screenresolution[1659:10750] starting screenresolution argv=screenresolution list 02:49:20 INFO - Available Modes on Display 0 02:49:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:20 INFO - Intel Iris: 02:49:20 INFO - Chipset Model: Intel Iris 02:49:20 INFO - Type: GPU 02:49:20 INFO - Bus: Built-In 02:49:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:20 INFO - Vendor: Intel (0x8086) 02:49:20 INFO - Device ID: 0x0a2e 02:49:20 INFO - Revision ID: 0x0009 02:49:20 INFO - Displays: 02:49:20 INFO - Display: 02:49:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:20 INFO - Display Serial Number: 1600x1200 60 02:49:20 INFO - Main Display: Yes 02:49:20 INFO - Mirror: Off 02:49:20 INFO - Online: Yes 02:49:20 INFO - Rotation: Supported 02:49:20 INFO - Return code: 0 02:49:20 INFO - Running post-action listener: _resource_record_post_action 02:49:20 INFO - [mozharness: 2017-04-13 09:49:20.974785Z] Finished run-tests step (success) 02:49:20 INFO - Running post-run listener: _resource_record_post_run 02:49:21 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 44718080; Write bytes: 515371008; Read time: 469; Write time: 2036 02:49:21 INFO - TinderboxPrint: CPU usage
54.7% 02:49:21 INFO - TinderboxPrint: I/O read bytes / time
44,718,080 / 469 02:49:21 INFO - TinderboxPrint: I/O write bytes / time
515,371,008 / 2,036 02:49:21 INFO - TinderboxPrint: CPU idle
2,176.8 (45.3%) 02:49:21 INFO - TinderboxPrint: CPU system
968.9 (20.2%) 02:49:21 INFO - TinderboxPrint: CPU user
1,655.9 (34.5%) 02:49:21 INFO - TinderboxPrint: Swap in / out
248,729,600 / 0 02:49:21 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200794624; Write bytes: 203192320; Read time: 15747; Write time: 456 02:49:21 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 43669504; Write bytes: 302258176; Read time: 452; Write time: 1548 02:49:21 INFO - Running post-run listener: _upload_blobber_files 02:49:21 INFO - Blob upload gear active. 02:49:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:22 INFO - (blobuploader) - INFO - Done attempting. 02:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:49:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:23 INFO - (blobuploader) - INFO - Done attempting. 02:49:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:49:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:49:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:24 INFO - (blobuploader) - INFO - Done attempting. 02:49:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:26 INFO - (blobuploader) - INFO - Done attempting. 02:49:26 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:26 INFO - Return code: 0 02:49:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36c1350c732dbd3c6b099cdfe58c881188b916cf420e3830e46a43635eff8ccdecd1229f0bbb62f810c97f799e66088ae5114b092ec658464b70c301d5d87115", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9347a342a6c327f5fd887060f2ea6940571a54a6fcdf3d9337faf302d220c83b3c7b7b7768434edc842988906304535c5741c110d9bf6175099309be94b1fffd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/975686ec82f0d0f71848f9f24e94858ac675c41c32a3fcc65298c51947febfe0eaf0baa5e01940ca266313671a4022a399c136faa05bf4619207fb8896afd988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4d407deab949ac79f53e963a6aa6bc1230334d014867983f760463dd9863dc86f0e4caf8f99e2dfa8f8bd2333b5b2b394185cc91512a51b194e7d34b817f3ef"} 02:49:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:26 INFO - Contents: 02:49:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36c1350c732dbd3c6b099cdfe58c881188b916cf420e3830e46a43635eff8ccdecd1229f0bbb62f810c97f799e66088ae5114b092ec658464b70c301d5d87115", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9347a342a6c327f5fd887060f2ea6940571a54a6fcdf3d9337faf302d220c83b3c7b7b7768434edc842988906304535c5741c110d9bf6175099309be94b1fffd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/975686ec82f0d0f71848f9f24e94858ac675c41c32a3fcc65298c51947febfe0eaf0baa5e01940ca266313671a4022a399c136faa05bf4619207fb8896afd988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4d407deab949ac79f53e963a6aa6bc1230334d014867983f760463dd9863dc86f0e4caf8f99e2dfa8f8bd2333b5b2b394185cc91512a51b194e7d34b817f3ef"} 02:49:26 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:26 INFO - Copying logs to upload dir... 02:49:26 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.808895 ========= master_lag: -0.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-13 02:49:25.818416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:49:25.824686) ========= 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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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/36c1350c732dbd3c6b099cdfe58c881188b916cf420e3830e46a43635eff8ccdecd1229f0bbb62f810c97f799e66088ae5114b092ec658464b70c301d5d87115", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9347a342a6c327f5fd887060f2ea6940571a54a6fcdf3d9337faf302d220c83b3c7b7b7768434edc842988906304535c5741c110d9bf6175099309be94b1fffd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/975686ec82f0d0f71848f9f24e94858ac675c41c32a3fcc65298c51947febfe0eaf0baa5e01940ca266313671a4022a399c136faa05bf4619207fb8896afd988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4d407deab949ac79f53e963a6aa6bc1230334d014867983f760463dd9863dc86f0e4caf8f99e2dfa8f8bd2333b5b2b394185cc91512a51b194e7d34b817f3ef"} build_url:https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/UjiFBotKQ-uuCLwfcgzxmQ/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/36c1350c732dbd3c6b099cdfe58c881188b916cf420e3830e46a43635eff8ccdecd1229f0bbb62f810c97f799e66088ae5114b092ec658464b70c301d5d87115", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9347a342a6c327f5fd887060f2ea6940571a54a6fcdf3d9337faf302d220c83b3c7b7b7768434edc842988906304535c5741c110d9bf6175099309be94b1fffd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/975686ec82f0d0f71848f9f24e94858ac675c41c32a3fcc65298c51947febfe0eaf0baa5e01940ca266313671a4022a399c136faa05bf4619207fb8896afd988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4d407deab949ac79f53e963a6aa6bc1230334d014867983f760463dd9863dc86f0e4caf8f99e2dfa8f8bd2333b5b2b394185cc91512a51b194e7d34b817f3ef"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:49:25.868218) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:49:25.868553) ========= 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.Pbn4RfXPAt/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.NLa9N7gpG1/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.005086 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:49:25.921017) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:49:25.924449) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:49:25.924828) ========= ========= Total master_lag: 0.07 =========